Page 1 of 1

SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 3:17 am
by Scoty
I switch from latest 3.6 to 3.7 RC1 and noticed that the startup takes almost 3 to 4 times as long. Can you do something that it starts normally fast again?

Re: SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 4:52 am
by sander
It would be useful if you post the relevant parts of sabnzbd.log of 3.6 versus 3.7 RC1 which proofs startup is 3 times slower.

With

Code: Select all

 cat .sabnzbd/logs/sabnzbd.log | grep -i -A1 -e "----------------"  -e "notification.* started "
I see

Code: Select all

2022-09-23 10:39:49,150::INFO::[sabnzbdplus:1168] --------------------------------
2022-09-23 10:39:49,162::INFO::[sabnzbdplus:1169] sabnzbdplus-3.6.1
--
2022-09-23 10:39:50,201::INFO::[notifier:123] Sending notification: SABnzbd - SABnzbd 3.6.1 started (type=startup, job_cat=None
So 1 second startup time

Code: Select all

2022-11-02 10:49:12,551::INFO::[sabnzbdplus:1168] --------------------------------
2022-11-02 10:49:12,556::INFO::[sabnzbdplus:1169] sabnzbdplus-3.7.0RC1
--
2022-11-02 10:49:13,412::INFO::[notifier:123] Sending notification: SABnzbd - SABnzbd 3.7.0RC1 started (type=startup, job_cat=None
)

And also 1 second startup time

Re: SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 5:05 am
by safihre
What platform are you on?
That matters a lot.

Re: SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 5:07 am
by Scoty
Windows 11 22H2 latest Beta Build.

Re: SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 6:13 am
by safihre
I suspect that it's Windows Defender or other security checks from Windows that do this.
On my Windows machine it starts instantly.

Re: SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 6:40 am
by Scoty
Here the log files

3.6
2022-11-02 10:54:49,774::INFO::[SABnzbd:1167] --------------------------------
2022-11-02 10:54:49,774::INFO::[SABnzbd:1168] SABnzbd.exe-3.6.1
2022-11-02 10:54:49,774::INFO::[SABnzbd:1178] Commit = fa844a6223631af0e73bda7b28db2eeb27ddd4c5
2022-11-02 10:54:49,774::INFO::[SABnzbd:1180] Full executable path = D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\SABnzbd.exe
2022-11-02 10:54:49,774::INFO::[SABnzbd:1181] Arguments = "D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\SABnzbd.exe" "-f" "sabnzbd.ini"
2022-11-02 10:54:49,774::INFO::[SABnzbd:1182] Python-version = 3.10.6 (tags/v3.10.6:9c7b4bd, Aug 1 2022, 21:53:49) [MSC v.1932 64 bit (AMD64)]
2022-11-02 10:54:49,774::INFO::[SABnzbd:1183] Dockerized = False
2022-11-02 10:54:49,774::INFO::[SABnzbd:1184] CPU architecture = AMD64
2022-11-02 10:54:49,790::INFO::[SABnzbd:1187] Platform = nt - Windows-10-10.0.22623-SP0
2022-11-02 10:54:49,790::INFO::[SABnzbd:1193] Preferred encoding = cp1252
2022-11-02 10:54:49,790::INFO::[SABnzbd:1215] SSL version = OpenSSL 1.1.1n 15 Mar 2022
2022-11-02 10:54:49,790::INFO::[SABnzbd:1224] Certifi version = 2022.06.15
2022-11-02 10:54:49,790::INFO::[SABnzbd:1225] Loaded additional certificates from D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\certifi\cacert.pem
2022-11-02 10:54:49,790::INFO::[SABnzbd:1246] Using INI file \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\sabnzbd.ini
2022-11-02 10:54:49,790::INFO::[postproc:134] Loading postproc queue
2022-11-02 10:54:49,790::INFO::[scheduler:188] Scheduling RSS interval task every 60 min (delay=55)
2022-11-02 10:54:49,790::INFO::[scheduler:213] Setting schedule for midnight BPS reset
2022-11-02 10:54:49,790::INFO::[scheduler:216] Setting schedule for server expiration check
2022-11-02 10:54:49,790::INFO::[scheduler:221] Setting schedule for server quota check
2022-11-02 10:54:49,790::INFO::[__init__:320] All processes started
2022-11-02 10:54:49,790::INFO::[SABnzbd:336] Template location for Glitter is \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\interfaces\Glitter
2022-11-02 10:54:49,790::INFO::[SABnzbd:336] Template location for Config is \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\interfaces\Config
2022-11-02 10:54:49,790::INFO::[SABnzbd:423] SABYenc module (v5.4.3)... found!
2022-11-02 10:54:49,790::INFO::[SABnzbd:424] SABYenc module is using SIMD set: AVX512VL+VBMI2
2022-11-02 10:54:49,790::INFO::[SABnzbd:443] Cryptography module (v37.0.4)... found!
2022-11-02 10:54:49,790::INFO::[SABnzbd:446] MultiPar binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\win\multipar\par2j64.exe)
2022-11-02 10:54:49,790::INFO::[SABnzbd:455] UNRAR binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\win\unrar\x64\UnRAR.exe)
2022-11-02 10:54:49,790::INFO::[SABnzbd:471] 7za binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.6\win\7zip\7za.exe)
2022-11-02 10:54:49,790::INFO::[SABnzbd:1435] Starting web-interface on 127.0.0.1:8081
2022-11-02 10:54:49,790::INFO::[_cplogging:213] [02/Nov/2022:10:54:49] ENGINE Bus STARTING
2022-11-02 10:54:50,026::INFO::[_cplogging:213] [02/Nov/2022:10:54:50] ENGINE Serving on http://127.0.0.1:8081
2022-11-02 10:54:50,026::INFO::[_cplogging:213] [02/Nov/2022:10:54:50] ENGINE Bus STARTED
2022-11-02 10:54:50,026::INFO::[SABnzbd:1474] Starting SABnzbd.exe-3.6.1
2022-11-02 10:54:50,026::INFO::[dirscanner:117] Dirscanner starting up
2022-11-02 10:54:50,026::INFO::[panic:239] Launching browser with http://127.0.0.1:8081/sabnzbd
2022-11-02 10:54:50,041::INFO::[notifier:123] Sending notification: SABnzbd - SABnzbd 3.6.1 gestartet (type=startup, job_cat=None)
3.7:
2022-11-02 11:16:28,820::INFO::[SABnzbd:1167] --------------------------------
2022-11-02 11:16:28,820::INFO::[SABnzbd:1168] SABnzbd.exe-3.7.0RC1
2022-11-02 11:16:28,820::INFO::[SABnzbd:1178] Commit = 6fce73855c6230cafe6be7333bb62c7ddf2b422e
2022-11-02 11:16:28,820::INFO::[SABnzbd:1180] Full executable path = D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\SABnzbd.exe
2022-11-02 11:16:28,821::INFO::[SABnzbd:1181] Arguments = "D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\SABnzbd.exe" "-f" "sabnzbd.ini"
2022-11-02 11:16:28,821::INFO::[SABnzbd:1182] Python-version = 3.11.0 (main, Oct 24 2022, 18:26:48) [MSC v.1933 64 bit (AMD64)]
2022-11-02 11:16:28,821::INFO::[SABnzbd:1183] Dockerized = False
2022-11-02 11:16:28,821::INFO::[SABnzbd:1184] CPU architecture = AMD64
2022-11-02 11:16:28,842::INFO::[SABnzbd:1187] Platform = nt - Windows-10-10.0.22623-SP0
2022-11-02 11:16:28,842::INFO::[SABnzbd:1193] Preferred encoding = cp1252
2022-11-02 11:16:28,842::INFO::[SABnzbd:1215] SSL version = OpenSSL 1.1.1q 5 Jul 2022
2022-11-02 11:16:28,842::INFO::[SABnzbd:1224] Certifi version = 2022.09.24
2022-11-02 11:16:28,842::INFO::[SABnzbd:1225] Loaded additional certificates from D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\certifi\cacert.pem
2022-11-02 11:16:28,843::INFO::[SABnzbd:1246] Using INI file \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\sabnzbd.ini
2022-11-02 11:16:28,844::INFO::[postproc:134] Loading postproc queue
2022-11-02 11:16:28,844::INFO::[scheduler:190] Scheduling RSS interval task every 60 min (delay=24)
2022-11-02 11:16:28,845::INFO::[scheduler:201] Scheduling VersionCheck on day 3 at 21:28
2022-11-02 11:16:28,845::INFO::[scheduler:215] Setting schedule for midnight BPS reset
2022-11-02 11:16:28,845::INFO::[scheduler:218] Setting schedule for server expiration check
2022-11-02 11:16:28,845::INFO::[scheduler:223] Setting schedule for server quota check
2022-11-02 11:16:28,845::INFO::[__init__:320] All processes started
2022-11-02 11:16:28,845::INFO::[SABnzbd:336] Template location for Glitter is \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\interfaces\Glitter
2022-11-02 11:16:28,845::INFO::[SABnzbd:336] Template location for Config is \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\interfaces\Config
2022-11-02 11:16:28,846::INFO::[SABnzbd:423] SABYenc module (v5.4.4)... found!
2022-11-02 11:16:28,846::INFO::[SABnzbd:424] SABYenc module is using SIMD set: AVX512VL+VBMI2
2022-11-02 11:16:28,846::INFO::[SABnzbd:443] Cryptography module (v38.0.1)... found!
2022-11-02 11:16:28,846::INFO::[SABnzbd:446] MultiPar binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\win\multipar\par2j64.exe)
2022-11-02 11:16:28,846::INFO::[SABnzbd:455] UNRAR binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\win\unrar\x64\UnRAR.exe)
2022-11-02 11:16:28,846::INFO::[SABnzbd:471] 7za binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\win\7zip\7za.exe)
2022-11-02 11:16:28,847::INFO::[SABnzbd:1435] Starting web-interface on 127.0.0.1:8081
2022-11-02 11:16:28,847::INFO::[_cplogging:213] [02/Nov/2022:11:16:28] ENGINE Bus STARTING
2022-11-02 11:16:29,062::INFO::[_cplogging:213] [02/Nov/2022:11:16:29] ENGINE Serving on http://127.0.0.1:8081
2022-11-02 11:16:29,062::INFO::[_cplogging:213] [02/Nov/2022:11:16:29] ENGINE Bus STARTED
2022-11-02 11:16:29,062::INFO::[SABnzbd:1474] Starting SABnzbd.exe-3.7.0RC1
2022-11-02 11:16:29,063::INFO::[dirscanner:117] Dirscanner starting up
2022-11-02 11:16:29,063::INFO::[panic:239] Launching browser with http://127.0.0.1:8081/sabnzbd
2022-11-02 11:16:29,089::INFO::[notifier:123] Sending notification: SABnzbd - SABnzbd 3.7.0RC1 gestartet (type=startup, job_cat=None)
2022-11-02 12:39:07,010::INFO::[SABnzbd:1167] --------------------------------
2022-11-02 12:39:07,010::INFO::[SABnzbd:1168] SABnzbd.exe-3.7.0RC1
2022-11-02 12:39:07,010::INFO::[SABnzbd:1178] Commit = 6fce73855c6230cafe6be7333bb62c7ddf2b422e
2022-11-02 12:39:07,010::INFO::[SABnzbd:1180] Full executable path = D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\SABnzbd.exe
2022-11-02 12:39:07,010::INFO::[SABnzbd:1181] Arguments = "D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\SABnzbd.exe" "-f" "sabnzbd.ini"
2022-11-02 12:39:07,010::INFO::[SABnzbd:1182] Python-version = 3.11.0 (main, Oct 24 2022, 18:26:48) [MSC v.1933 64 bit (AMD64)]
2022-11-02 12:39:07,010::INFO::[SABnzbd:1183] Dockerized = False
2022-11-02 12:39:07,010::INFO::[SABnzbd:1184] CPU architecture = AMD64
2022-11-02 12:39:07,026::INFO::[SABnzbd:1187] Platform = nt - Windows-10-10.0.22623-SP0
2022-11-02 12:39:07,026::INFO::[SABnzbd:1193] Preferred encoding = cp1252
2022-11-02 12:39:07,026::INFO::[SABnzbd:1215] SSL version = OpenSSL 1.1.1q 5 Jul 2022
2022-11-02 12:39:07,026::INFO::[SABnzbd:1224] Certifi version = 2022.09.24
2022-11-02 12:39:07,026::INFO::[SABnzbd:1225] Loaded additional certificates from D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\certifi\cacert.pem
2022-11-02 12:39:07,026::INFO::[SABnzbd:1246] Using INI file \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\sabnzbd.ini
2022-11-02 12:39:07,026::INFO::[postproc:134] Loading postproc queue
2022-11-02 12:39:07,041::INFO::[scheduler:190] Scheduling RSS interval task every 60 min (delay=37)
2022-11-02 12:39:07,041::INFO::[scheduler:201] Scheduling VersionCheck on day 6 at 9:22
2022-11-02 12:39:07,041::INFO::[scheduler:215] Setting schedule for midnight BPS reset
2022-11-02 12:39:07,041::INFO::[scheduler:218] Setting schedule for server expiration check
2022-11-02 12:39:07,041::INFO::[scheduler:223] Setting schedule for server quota check
2022-11-02 12:39:07,041::INFO::[__init__:320] All processes started
2022-11-02 12:39:07,041::INFO::[SABnzbd:336] Template location for Glitter is \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\interfaces\Glitter
2022-11-02 12:39:07,041::INFO::[SABnzbd:336] Template location for Config is \\?\D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\interfaces\Config
2022-11-02 12:39:07,041::INFO::[SABnzbd:423] SABYenc module (v5.4.4)... found!
2022-11-02 12:39:07,041::INFO::[SABnzbd:424] SABYenc module is using SIMD set: AVX512VL+VBMI2
2022-11-02 12:39:07,041::INFO::[SABnzbd:443] Cryptography module (v38.0.1)... found!
2022-11-02 12:39:07,041::INFO::[SABnzbd:446] MultiPar binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\win\multipar\par2j64.exe)
2022-11-02 12:39:07,041::INFO::[SABnzbd:455] UNRAR binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\win\unrar\x64\UnRAR.exe)
2022-11-02 12:39:07,041::INFO::[SABnzbd:471] 7za binary... found (D:\Software_Tools\Newsreader-FTP\SABnzbd-3.7\win\7zip\7za.exe)
2022-11-02 12:39:07,041::INFO::[SABnzbd:1435] Starting web-interface on 127.0.0.1:8081
2022-11-02 12:39:07,041::INFO::[_cplogging:213] [02/Nov/2022:12:39:07] ENGINE Bus STARTING
2022-11-02 12:39:07,250::INFO::[_cplogging:213] [02/Nov/2022:12:39:07] ENGINE Serving on http://127.0.0.1:8081
2022-11-02 12:39:07,250::INFO::[_cplogging:213] [02/Nov/2022:12:39:07] ENGINE Bus STARTED
2022-11-02 12:39:07,251::INFO::[SABnzbd:1474] Starting SABnzbd.exe-3.7.0RC1
2022-11-02 12:39:07,251::INFO::[dirscanner:117] Dirscanner starting up
2022-11-02 12:39:07,251::INFO::[panic:239] Launching browser with http://127.0.0.1:8081/sabnzbd
2022-11-02 12:39:07,275::INFO::[notifier:123] Sending notification: SABnzbd - SABnzbd 3.7.0RC1 gestartet (type=startup, job_cat=None)

Re: SABnzbd 3.7.0 Release Candidate 1 slow startup

Posted: November 2nd, 2022, 7:06 am
by safihre
Yes, the actual startup seems to take only 1 second. It's the time before that where it's probably stuck (why I suspect Windows security).