spksrc icon indicating copy to clipboard operation
spksrc copied to clipboard

SABnzbd 4.2.2 creates files with incorrect permissions (600 instead of 666) when using 7za to unpack

Open JustAndreww opened this issue 1 year ago • 38 comments

Is this a new Bug?

  • [X] I checked that the bug hasn't been reported before

Package Name

SABnzbd

Package Version

4.2.2

Device Model

DS920+

Device Architecture

x86_64

Firmware Version

DSM 7.2.1-69057 Update 4

What happened?

When unpacking 7Z downloads resulted file will have 600 permissions instead of 666. So I can't open it in Windows in mounted network drive. Doesn't happen with RAR downloads.

Permissions are set as follows for the downloading folder: image

Reproduction steps

Download some NZB with 7z inside. Unpack will finish OK, resulted file cannot be opened in the mounted Windows drive folder due to lack of access rights. No errors or whatsoever in logs, everything seems to be fine.

Install Log

https://pastebin.com/dXQHvhaD

ls -l output

ash-4.4# ls -l 7z_download/
-rw------- 1 sc-sabnzbd synocommunity 827135139 Jan 31 09:31 7z.mkv
--
ash-4.4# ls -l rar_download/
-rw-rw-rw- 1 sc-sabnzbd synocommunity     304997 Feb  8 12:27 rar.jpg
-rw-rw-rw- 1 sc-sabnzbd synocommunity 3589946749 Feb  8 12:26 rar.mp4

Sabnzbd log with 7za arguments call

Log file
2024-02-08 18:13:10,205::INFO::[database:279] Added job XXXXX to history
2024-02-08 18:13:10,206::INFO::[postproc:138] Saving postproc queue
2024-02-08 18:13:10,206::DEBUG::[filesystem:1198] [sabnzbd.postproc.save] Saving data for postproc2.sab
2024-02-08 18:13:10,206::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for postproc2.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:13:10,206::INFO::[downloader:422] Post-processing finished, resuming download
2024-02-08 18:13:14,206::DEBUG::[articlecache:140] Saving 0 cached articles to disk
2024-02-08 18:13:14,206::INFO::[nzbqueue:230] Saving queue
2024-02-08 18:13:14,207::DEBUG::[filesystem:1198] [sabnzbd.nzbqueue.save] Saving data for queue10.sab
2024-02-08 18:13:14,207::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for queue10.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:13:14,207::DEBUG::[filesystem:1198] [sabnzbd.bpsmeter.save] Saving data for totals10.sab
2024-02-08 18:13:14,207::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for totals10.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:13:14,208::DEBUG::[filesystem:1198] [sabnzbd.dirscanner.save] Saving data for watched_data2.sab
2024-02-08 18:13:14,208::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for watched_data2.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:13:14,208::INFO::[postproc:138] Saving postproc queue
2024-02-08 18:13:14,208::DEBUG::[filesystem:1198] [sabnzbd.postproc.save] Saving data for postproc2.sab
2024-02-08 18:13:14,208::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for postproc2.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:13:14,208::DEBUG::[filesystem:1198] [sabnzbd.rss.save] Saving data for rss_data.sab
2024-02-08 18:13:14,208::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for rss_data.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:13:14,209::INFO::[notifier:142] Sending notification: SABnzbd - Queue finished (type=queue_done, job_cat=None)
2024-02-08 18:16:23,301::INFO::[nzbparser:87] Attempting to add XXXXX.nzb
2024-02-08 18:16:23,301::INFO::[nzbstuff:749] Replacing spaces with underscores in XXXXX
2024-02-08 18:16:23,301::INFO::[filesystem:746] Creating directories: /volume2/homes/silent/NZB/Incomplete/XXXXX
2024-02-08 18:16:23,301::INFO::[filesystem:746] Creating directories: /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,302::INFO::[filesystem:1239] Saving /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/XXXXX.nzb.gz
2024-02-08 18:16:23,304::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_qurh94x9 in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,304::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.001 added to queue
2024-02-08 18:16:23,305::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_hiq6zjdg in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,305::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.002 added to queue
2024-02-08 18:16:23,306::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_yy16jt4w in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,306::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.003 added to queue
2024-02-08 18:16:23,307::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_z2pn6q3k in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,307::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.004 added to queue
2024-02-08 18:16:23,307::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_o6b0d_0e in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,307::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.005 added to queue
2024-02-08 18:16:23,309::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_96un0tvu in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,309::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.006 added to queue
2024-02-08 18:16:23,309::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_88sr65bc in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,309::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.007 added to queue
2024-02-08 18:16:23,310::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_who8j1kt in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,310::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.008 added to queue
2024-02-08 18:16:23,311::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_e_e0o9ud in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,311::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.009 added to queue
2024-02-08 18:16:23,311::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_ju53sxbd in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,312::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.011 added to queue
2024-02-08 18:16:23,312::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_28newher in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,312::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.010 added to queue
2024-02-08 18:16:23,313::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_hmraeahy in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,313::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.012 added to queue
2024-02-08 18:16:23,314::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_br8vgg7b in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,314::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.013 added to queue
2024-02-08 18:16:23,314::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_i_l7_cn_ in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,314::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.014 added to queue
2024-02-08 18:16:23,316::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_2bt488nl in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,316::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.015 added to queue
2024-02-08 18:16:23,316::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_xow14oya in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,316::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.016 added to queue
2024-02-08 18:16:23,317::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_31ijrprw in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,317::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.017 added to queue
2024-02-08 18:16:23,317::INFO::[nzbstuff:961] File XXXXX.vol-01.par2 added to queue
2024-02-08 18:16:23,318::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_k7bc63zv in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,318::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.018 added to queue
2024-02-08 18:16:23,319::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_ki7zc7tc in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,319::INFO::[nzbstuff:961] File nODRrtI6XBqzq1ZyHAXY6W.7z.019 added to queue
2024-02-08 18:16:23,319::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_fc0191b8 in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,319::INFO::[nzbstuff:961] File XXXXX.vol-02.par2 added to queue
2024-02-08 18:16:23,320::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_x0ohh2p7 in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,320::INFO::[nzbstuff:961] File XXXXX.vol-03.par2 added to queue
2024-02-08 18:16:23,320::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_rms44rjy in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,320::INFO::[nzbstuff:961] File XXXXX.vol-04.par2 added to queue
2024-02-08 18:16:23,321::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.__init__] Saving data for SABnzbd_nzf_wzv_hwzm in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,321::INFO::[nzbstuff:961] File XXXXX.vol-05.par2 added to queue
2024-02-08 18:16:23,321::DEBUG::[misc:264] Parsing category * to attributes: pp=3 script=None prio=0
2024-02-08 18:16:23,321::DEBUG::[nzbstuff:931] NZB nzo-info = {}
2024-02-08 18:16:23,321::INFO::[nzbqueue:230] Saving queue
2024-02-08 18:16:23,321::DEBUG::[nzbstuff:1874] Saving attributes {'cat': '*', 'pp': 3, 'script': 'None', 'priority': 0, 'final_name': 'XXXXX', 'password': None, 'url': None} for XXXXX
2024-02-08 18:16:23,322::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.save_to_disk] Saving data for SABnzbd_nzo_ac964olv in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,322::DEBUG::[filesystem:1198] [sabnzbd.nzbqueue.save] Saving data for queue10.sab
2024-02-08 18:16:23,322::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for queue10.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:16:23,322::INFO::[notifier:142] Sending notification: NZB added to queue - XXXXX.nzb (type=download, job_cat=*)
2024-02-08 18:16:23,323::DEBUG::[filesystem:952] [sabnzbd.nzbparser.process_single_nzb] Deleting file /tmp/tmpp1sq_k8j.nzb
2024-02-08 18:16:23,323::DEBUG::[downloader:225] Retrieving server address information for news.newshosting.com
2024-02-08 18:16:23,323::DEBUG::[downloader:225] Retrieving server address information for eu.newsdemon.com
2024-02-08 18:16:23,323::DEBUG::[downloader:225] Retrieving server address information for eu.usenetprime.com
2024-02-08 18:16:23,428::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.001
2024-02-08 18:16:23,428::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_qurh94x9 from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_qurh94x9
2024-02-08 18:16:23,995::DEBUG::[deobfuscate_filenames:112] Checking: nODRrtI6XBqzq1ZyHAXY6W
2024-02-08 18:16:23,995::DEBUG::[deobfuscate_filenames:168] Obfuscated (default)
2024-02-08 18:16:23,995::DEBUG::[nzbstuff:1874] Saving attributes {'cat': '*', 'pp': 3, 'script': 'None', 'priority': 0, 'final_name': 'XXXXX', 'password': None, 'url': None} for XXXXX
2024-02-08 18:16:23,995::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-01.par2
2024-02-08 18:16:23,995::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-01.par2
2024-02-08 18:16:23,995::DEBUG::[filesystem:1130] [sabnzbd.nzbstuff.save_to_disk] Saving data for SABnzbd_nzo_ac964olv in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:16:23,996::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-01.par2
2024-02-08 18:16:23,996::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_xfk7tevr
2024-02-08 18:16:23,997::DEBUG::[filesystem:1198] [sabnzbd.bpsmeter.save] Saving data for totals10.sab
2024-02-08 18:16:23,997::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_xfk7tevr
2024-02-08 18:16:23,997::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for totals10.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:16:23,998::DEBUG::[par2file:175] Par2-creator of XXXXX.vol-01.par2 is: ParPar v0.4.3 x64 [https://animetosho.org/app/parpar]
2024-02-08 18:16:23,999::DEBUG::[nzbstuff:1059] Got par2pack for set XXXXX
2024-02-08 18:16:23,999::INFO::[nzbstuff:1765] Checking all filenames for XXXXX
2024-02-08 18:16:23,999::INFO::[nzbstuff:1768] Re-sorting XXXXX after getting filename information
2024-02-08 18:16:24,487::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:16:24,529::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:16:24,582::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:16:24,614::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:16:24,625::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:16:24,646::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:16:24,879::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:16:25,132::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:16:25,175::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:16:25,206::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:16:25,206::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_hiq6zjdg from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_hiq6zjdg
2024-02-08 18:16:25,249::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:16:25,512::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:16:26,976::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:16:26,977::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_yy16jt4w from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_yy16jt4w
2024-02-08 18:16:27,191::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:16:27,903::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:16:28,061::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:16:28,075::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:16:28,075::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:16:28,075::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_hiq6zjdg
2024-02-08 18:16:28,075::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_hiq6zjdg
2024-02-08 18:16:28,700::DEBUG::[bpsmeter:351] Speed: 22.7 MB/s
2024-02-08 18:16:28,836::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:16:28,836::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_z2pn6q3k from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_z2pn6q3k
2024-02-08 18:16:29,087::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:16:30,708::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:16:30,708::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_o6b0d_0e from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_o6b0d_0e
2024-02-08 18:16:30,709::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:16:30,722::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:16:30,723::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:16:30,723::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_yy16jt4w
2024-02-08 18:16:30,723::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_yy16jt4w
2024-02-08 18:16:31,713::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:16:32,433::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.006
2024-02-08 18:16:32,433::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_96un0tvu from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_96un0tvu
2024-02-08 18:16:32,977::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:16:33,710::DEBUG::[bpsmeter:351] Speed: 22.8 MB/s
2024-02-08 18:16:34,328::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:16:34,328::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_88sr65bc from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_88sr65bc
2024-02-08 18:16:34,402::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:16:34,414::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:16:34,414::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:16:34,414::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_z2pn6q3k
2024-02-08 18:16:34,415::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_z2pn6q3k
2024-02-08 18:16:34,695::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:16:34,707::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:16:34,707::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:16:34,707::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_o6b0d_0e
2024-02-08 18:16:34,707::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_o6b0d_0e
2024-02-08 18:16:36,154::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:16:36,155::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_who8j1kt from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_who8j1kt
2024-02-08 18:16:38,053::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:16:38,053::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_e_e0o9ud from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_e_e0o9ud
2024-02-08 18:16:38,170::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:16:38,183::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:16:38,183::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:16:38,183::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_88sr65bc
2024-02-08 18:16:38,183::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_88sr65bc
2024-02-08 18:16:38,712::DEBUG::[bpsmeter:351] Speed: 22.8 MB/s
2024-02-08 18:16:39,005::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:16:39,809::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:16:39,809::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_28newher from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_28newher
2024-02-08 18:16:41,714::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:16:41,714::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_ju53sxbd from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_ju53sxbd
2024-02-08 18:16:42,211::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006
2024-02-08 18:16:42,223::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006
2024-02-08 18:16:42,223::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006
2024-02-08 18:16:42,223::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_96un0tvu
2024-02-08 18:16:42,223::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_96un0tvu
2024-02-08 18:16:42,609::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
2024-02-08 18:16:42,621::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
2024-02-08 18:16:42,621::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
2024-02-08 18:16:42,621::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_qurh94x9
2024-02-08 18:16:42,621::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_qurh94x9
2024-02-08 18:16:42,849::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:16:42,861::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:16:42,861::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:16:42,861::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_who8j1kt
2024-02-08 18:16:42,861::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_who8j1kt
2024-02-08 18:16:43,162::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:16:43,176::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:16:43,176::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:16:43,176::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_28newher
2024-02-08 18:16:43,176::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_28newher
2024-02-08 18:16:43,475::DEBUG::[deobfuscate_filenames:112] Checking: nODRrtI6XBqzq1ZyHAXY6W.vol01+02
2024-02-08 18:16:43,475::DEBUG::[deobfuscate_filenames:149] Not obfuscated: upperchars >= 2 and lowerchars >= 2  and spacesdots >= 1
2024-02-08 18:16:43,475::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-02.par2
2024-02-08 18:16:43,507::DEBUG::[deobfuscate_filenames:112] Checking: nODRrtI6XBqzq1ZyHAXY6W.vol03+04
2024-02-08 18:16:43,507::DEBUG::[deobfuscate_filenames:149] Not obfuscated: upperchars >= 2 and lowerchars >= 2  and spacesdots >= 1
2024-02-08 18:16:43,507::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-03.par2
2024-02-08 18:16:43,528::DEBUG::[deobfuscate_filenames:112] Checking: nODRrtI6XBqzq1ZyHAXY6W.vol00+01
2024-02-08 18:16:43,528::DEBUG::[deobfuscate_filenames:149] Not obfuscated: upperchars >= 2 and lowerchars >= 2  and spacesdots >= 1
2024-02-08 18:16:43,529::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:16:43,529::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-04.par2
2024-02-08 18:16:43,529::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_hmraeahy from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_hmraeahy
2024-02-08 18:16:43,560::DEBUG::[deobfuscate_filenames:112] Checking: nODRrtI6XBqzq1ZyHAXY6W.vol07+07
2024-02-08 18:16:43,561::DEBUG::[deobfuscate_filenames:149] Not obfuscated: upperchars >= 2 and lowerchars >= 2  and spacesdots >= 1
2024-02-08 18:16:43,561::INFO::[nzbstuff:1765] Checking all filenames for XXXXX
2024-02-08 18:16:43,561::INFO::[nzbstuff:1768] Re-sorting XXXXX after getting filename information
2024-02-08 18:16:43,561::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-05.par2
2024-02-08 18:16:43,718::DEBUG::[bpsmeter:351] Speed: 22.8 MB/s
2024-02-08 18:16:44,813::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:16:44,826::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:16:44,826::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:16:44,827::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_e_e0o9ud
2024-02-08 18:16:44,827::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_e_e0o9ud
2024-02-08 18:16:45,397::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:16:45,398::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_br8vgg7b from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_br8vgg7b
2024-02-08 18:16:47,359::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:16:47,359::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_i_l7_cn_ from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_i_l7_cn_
2024-02-08 18:16:47,881::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:16:47,893::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:16:47,893::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:16:47,893::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_hmraeahy
2024-02-08 18:16:47,894::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_hmraeahy
2024-02-08 18:16:48,726::DEBUG::[bpsmeter:351] Speed: 22.7 MB/s
2024-02-08 18:16:48,925::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:16:48,938::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:16:48,939::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:16:48,939::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_ju53sxbd
2024-02-08 18:16:48,939::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_ju53sxbd
2024-02-08 18:16:49,175::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:16:49,176::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_2bt488nl from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_2bt488nl
2024-02-08 18:16:49,520::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:16:49,532::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:16:49,532::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:16:49,532::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_br8vgg7b
2024-02-08 18:16:49,532::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_br8vgg7b
2024-02-08 18:16:51,045::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:16:51,045::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_xow14oya from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_xow14oya
2024-02-08 18:16:51,619::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:16:51,631::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:16:51,631::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:16:51,631::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_i_l7_cn_
2024-02-08 18:16:51,632::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_i_l7_cn_
2024-02-08 18:16:52,870::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:16:52,871::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_31ijrprw from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_31ijrprw
2024-02-08 18:16:53,570::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:16:53,582::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:16:53,582::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:16:53,582::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_2bt488nl
2024-02-08 18:16:53,582::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_2bt488nl
2024-02-08 18:16:53,726::DEBUG::[bpsmeter:351] Speed: 22.7 MB/s
2024-02-08 18:16:54,738::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:16:54,739::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_k7bc63zv from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_k7bc63zv
2024-02-08 18:16:54,927::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:16:54,939::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:16:54,940::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:16:54,940::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_xow14oya
2024-02-08 18:16:54,940::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_xow14oya
2024-02-08 18:16:56,646::DEBUG::[nzbstuff:386] Finishing import on nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:16:56,646::DEBUG::[filesystem:1163] [sabnzbd.nzbstuff.finish_import] Loading data for SABnzbd_nzf_ki7zc7tc from /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_ki7zc7tc
2024-02-08 18:16:57,075::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:16:57,087::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:16:57,087::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:16:57,087::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_31ijrprw
2024-02-08 18:16:57,087::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_31ijrprw
2024-02-08 18:16:58,733::DEBUG::[bpsmeter:351] Speed: 22.7 MB/s
2024-02-08 18:16:59,100::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:16:59,111::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:16:59,111::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:16:59,111::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_k7bc63zv
2024-02-08 18:16:59,112::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_k7bc63zv
2024-02-08 18:16:59,571::INFO::[nzbqueue:768] [sabnzbd.nzbqueue.register_article] Ending job XXXXX
2024-02-08 18:16:59,571::DEBUG::[assembler:80] Decoding part of /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:16:59,580::DEBUG::[filesystem:650] Applying permissions 0o100666 (octal) to /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:16:59,580::INFO::[assembler:88] Decoding finished /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:16:59,580::DEBUG::[nzbstuff:460] Removing article database for SABnzbd_nzf_ki7zc7tc
2024-02-08 18:16:59,580::DEBUG::[filesystem:952] [sabnzbd.nzbstuff.remove_admin] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/SABnzbd_nzf_ki7zc7tc
2024-02-08 18:16:59,581::INFO::[nzbqueue:370] [sabnzbd.assembler.run] Removing job XXXXX
2024-02-08 18:16:59,581::INFO::[nzbqueue:230] Saving queue
2024-02-08 18:16:59,581::DEBUG::[filesystem:1198] [sabnzbd.nzbqueue.save] Saving data for queue10.sab
2024-02-08 18:16:59,581::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for queue10.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:16:59,581::INFO::[postproc:138] Saving postproc queue
2024-02-08 18:16:59,581::DEBUG::[filesystem:1198] [sabnzbd.postproc.save] Saving data for postproc2.sab
2024-02-08 18:16:59,581::DEBUG::[filesystem:1130] [sabnzbd.filesystem.save_admin] Saving data for postproc2.sab in /volume1/@appdata/sabnzbd/admin
2024-02-08 18:17:00,331::DEBUG::[nzbstuff:1874] Saving attributes {'cat': '*', 'pp': 3, 'script': 'None', 'priority': 0, 'final_name': 'XXXXX', 'password': None, 'url': None} for XXXXX
2024-02-08 18:17:00,331::INFO::[postproc:415] Starting Post-Processing on XXXXX => Repair:True, Unpack:True, Delete:True, Script:None, Cat:*
2024-02-08 18:17:00,331::INFO::[notifier:142] Sending notification: Post-processing - XXXXX (type=pp, job_cat=*)
2024-02-08 18:17:00,331::INFO::[postproc:779] Starting verification and repair of XXXXX
2024-02-08 18:17:00,331::INFO::[filesystem:1159] [sabnzbd.postproc.parring] /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__/__verified__ missing
2024-02-08 18:17:00,331::DEBUG::[postproc:785] Verified sets: {}
2024-02-08 18:17:00,331::INFO::[postproc:802] Running verification and repair on set XXXXX
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.001 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.002 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.003 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.004 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.005 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.006 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.007 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.008 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.009 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.010 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.011 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.012 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.013 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.014 OK
2024-02-08 18:17:00,332::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.015 OK
2024-02-08 18:17:00,333::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.016 OK
2024-02-08 18:17:00,333::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.017 OK
2024-02-08 18:17:00,333::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.018 OK
2024-02-08 18:17:00,333::DEBUG::[newsunpack:1889] Quick-check of file nODRrtI6XBqzq1ZyHAXY6W.7z.019 OK
2024-02-08 18:17:00,333::INFO::[newsunpack:1037] Quick-check for XXXXX is OK, skipping repair
2024-02-08 18:17:00,333::DEBUG::[filesystem:952] [sabnzbd.newsunpack.par2_repair] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-02.par2
2024-02-08 18:17:00,333::DEBUG::[filesystem:952] [sabnzbd.newsunpack.par2_repair] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-03.par2
2024-02-08 18:17:00,334::DEBUG::[filesystem:952] [sabnzbd.newsunpack.par2_repair] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-04.par2
2024-02-08 18:17:00,334::DEBUG::[filesystem:952] [sabnzbd.newsunpack.par2_repair] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-05.par2
2024-02-08 18:17:00,334::DEBUG::[filesystem:952] [sabnzbd.newsunpack.par2_repair] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/XXXXX.vol-01.par2
2024-02-08 18:17:00,334::DEBUG::[filesystem:541] build_filelists(): joinables: []
2024-02-08 18:17:00,334::DEBUG::[filesystem:542] build_filelists(): rars: []
2024-02-08 18:17:00,334::DEBUG::[filesystem:543] build_filelists(): 7zips: ['/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001']
2024-02-08 18:17:00,335::DEBUG::[filesystem:544] build_filelists(): ts: []
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:17:00,335::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:17:00,336::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:17:00,337::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:17:00,337::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006
2024-02-08 18:17:00,337::DEBUG::[postproc:996] No RAR-volume-number found in /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
2024-02-08 18:17:00,337::DEBUG::[postproc:998] Deobfuscate: rarvolnr is: {}
2024-02-08 18:17:00,337::DEBUG::[postproc:999] Deobfuscate: volnrext is: {}
2024-02-08 18:17:00,337::DEBUG::[postproc:857] Verified sets: {'XXXXX': True}
2024-02-08 18:17:00,337::DEBUG::[filesystem:1130] [sabnzbd.postproc.parring] Saving data for __verified__ in /volume2/homes/silent/NZB/Incomplete/XXXXX/__ADMIN__
2024-02-08 18:17:00,337::INFO::[postproc:860] Verification and repair finished for XXXXX
2024-02-08 18:17:00,337::INFO::[downloader:427] Forcing disconnect
2024-02-08 18:17:00,337::INFO::[filesystem:340] Checking if any resulting filenames need to be sanitized
2024-02-08 18:17:00,337::DEBUG::[downloader:941] Thread [email protected]: Forcing disconnect
2024-02-08 18:17:00,338::DEBUG::[sorting:171] No matching sorter found for job XXXXX
2024-02-08 18:17:00,338::INFO::[filesystem:746] Creating directories: /volume2/homes/silent/NZB/Complete/XXXXX
2024-02-08 18:17:00,339::DEBUG::[filesystem:916] Renaming "/volume2/homes/silent/NZB/Complete/XXXXX" to "/volume2/homes/silent/NZB/Complete/_UNPACK_XXXXX"
2024-02-08 18:17:00,339::INFO::[postproc:473] Running unpacker on XXXXX
2024-02-08 18:17:00,339::DEBUG::[filesystem:541] build_filelists(): joinables: []
2024-02-08 18:17:00,339::DEBUG::[filesystem:542] build_filelists(): rars: []
2024-02-08 18:17:00,340::DEBUG::[filesystem:543] build_filelists(): 7zips: ['/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001']
2024-02-08 18:17:00,340::DEBUG::[filesystem:544] build_filelists(): ts: []
2024-02-08 18:17:00,340::INFO::[newsunpack:319] 7za starting on /volume2/homes/silent/NZB/Incomplete/XXXXX
2024-02-08 18:17:00,340::INFO::[newsunpack:891] Starting extract on 7zip set/file: nODRrtI6XBqzq1ZyHAXY6W.7z 
2024-02-08 18:17:00,340::INFO::[misc:1232] [sabnzbd.newsunpack.seven_extract_core] Running external command: ['/volume1/@appstore/sabnzbd/bin/7za', 'x', '-y', '-aou', '-ssc', '-p', '-o/volume2/homes/silent/NZB/Complete/_UNPACK_XXXXX', '/volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001']
2024-02-08 18:17:00,340::DEBUG::[misc:1233] Popen arguments: {'stdout': -1, 'stderr': -2, 'bufsize': 0, 'startupinfo': None, 'creationflags': 0, 'text': True, 'encoding': 'utf8', 'errors': 'replace'}
2024-02-08 18:17:01,336::DEBUG::[newsunpack:972] 7za output: 
7-Zip (a) [64] 16.02 : Copyright (c) 1999-2016 Igor Pavlov : 2016-05-21
p7zip Version 16.02 (locale=en_US.UTF-8,Utf16=on,HugeFiles=on,64 bits,4 CPUs x64)

Scanning the drive for archives:
1 file, 44040192 bytes (42 MiB)

Extracting archive: /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
--
Path = /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
Type = Split
Physical Size = 44040192
Volumes = 19
Total Physical Size = 827135381
----
Path = nODRrtI6XBqzq1ZyHAXY6W.7z
Size = 827135381
--
Path = nODRrtI6XBqzq1ZyHAXY6W.7z
Type = 7z
Physical Size = 827135381
Headers Size = 242
Method = Copy
Solid = -
Blocks = 1

Everything is Ok

Size:       827135139
Compressed: 827135381

2024-02-08 18:17:01,337::INFO::[newsunpack:1001] Unpacked 1 files/folders in 0 seconds
2024-02-08 18:17:01,337::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.001
2024-02-08 18:17:01,342::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.002
2024-02-08 18:17:01,347::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.003
2024-02-08 18:17:01,348::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.004
2024-02-08 18:17:01,353::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.005
2024-02-08 18:17:01,358::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.006
2024-02-08 18:17:01,365::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.007
2024-02-08 18:17:01,370::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.008
2024-02-08 18:17:01,375::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.009
2024-02-08 18:17:01,375::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.010
2024-02-08 18:17:01,380::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.011
2024-02-08 18:17:01,385::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.012
2024-02-08 18:17:01,390::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.013
2024-02-08 18:17:01,395::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.014
2024-02-08 18:17:01,399::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.015
2024-02-08 18:17:01,405::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.016
2024-02-08 18:17:01,411::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.017
2024-02-08 18:17:01,416::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.018
2024-02-08 18:17:01,423::DEBUG::[filesystem:952] [sabnzbd.newsunpack.unseven] Deleting file /volume2/homes/silent/NZB/Incomplete/XXXXX/nODRrtI6XBqzq1ZyHAXY6W.7z.019
2024-02-08 18:17:01,423::INFO::[newsunpack:323] 7za finished on /volume2/homes/silent/NZB/Incomplete/XXXXX
2024-02-08 18:17:01,423::DEBUG::[filesystem:541] build_filelists(): joinables: []
2024-02-08 18:17:01,423::DEBUG::[filesystem:542] build_filelists(): rars: []
2024-02-08 18:17:01,423::DEBUG::[filesystem:543] build_filelists(): 7zips: []
2024-02-08 18:17:01,423::DEBUG::[filesystem:544] build_filelists(): ts: []
2024-02-08 18:17:01,423::INFO::[postproc:475] Unpacked files ['/volume2/homes/silent/NZB/Complete/_UNPACK_XXXXX/XXXXX.mkv']
2024-02-08 18:17:01,423::INFO::[filesystem:340] Checking if any resulting filenames need to be sanitized
2024-02-08 18:17:01,424::INFO::[postproc:479] Finished unpack_magic on XXXXX
2024-02-08 18:17:01,424::DEBUG::[filesystem:916] Renaming "/volume2/homes/silent/NZB/Complete/_UNPACK_XXXXX" to "/volume2/homes/silent/NZB/Complete/XXXXX"
2024-02-08 18:17:01,424::DEBUG::[filesystem:959] [sabnzbd.postproc.rename_and_collapse_folder] Removing dir /volume2/homes/silent/NZB/Complete/_UNPACK_XXXXX
2024-02-08 18:17:01,424::DEBUG::[deobfuscate_filenames:89] No additional par2 files found to process
2024-02-08 18:17:01,424::INFO::[postproc:573] Running deobfuscate
2024-02-08 18:17:01,424::DEBUG::[deobfuscate_filenames:248] Extension of /volume2/homes/silent/NZB/Complete/XXXXX/XXXXX.mkv looks common
2024-02-08 18:17:01,424::DEBUG::[deobfuscate_filenames:267] Trying to see if there are qualifying files to be deobfuscated
2024-02-08 18:17:01,424::DEBUG::[deobfuscate_filenames:281] Deobfuscate inspecting biggest file/volume2/homes/silent/NZB/Complete/XXXXX/XXXXX.mkv
2024-02-08 18:17:01,425::DEBUG::[deobfuscate_filenames:112] Checking: XXXXX
2024-02-08 18:17:01,425::DEBUG::[deobfuscate_filenames:149] Not obfuscated: upperchars >= 2 and lowerchars >= 2  and spacesdots >= 1
2024-02-08 18:17:01,425::DEBUG::[deobfuscate_filenames:289] /volume2/homes/silent/NZB/Complete/XXXXX/XXXXX.mkv excluded from deobfuscation because filename does not look obfuscated
2024-02-08 18:17:01,425::INFO::[nzbstuff:1806] [sabnzbd.postproc.process_job] Purging data for job XXXXX (delete_all_data=True)
2024-02-08 18:17:01,425::DEBUG::[articlecache:152] Purging 0 articles from the cache/disk
2024-02-08 18:17:01,425::DEBUG::[filesystem:985] Removing dir recursively /volume2/homes/silent/NZB/Incomplete/XXXXX
2024-02-08 18:17:01,425::INFO::[notifier:142] Sending notification: Download Completed - XXXXX (type=complete, job_cat=*)

JustAndreww avatar Feb 08 '24 11:02 JustAndreww

@Safihre, could this maybe be an upstream issue?

@JustAndreww, can you indicate if this unpack function had the correct permissions in previous versions? It would be helpful in understanding if there was a specific version where it did work correctly.

mreid-tt avatar Feb 08 '24 12:02 mreid-tt

I would love to, but I have only recently installed Sabnzbd and never used it before, so I can't really compare.

JustAndreww avatar Feb 08 '24 12:02 JustAndreww

Is is a bug in p7zip. Unfortunately the version used by SynoCommunity is 16.02, where the 16 stands for 2016. p7zip hasn't been updated since. We could just try to compile the official 7zip (without the "p"), since it has good Unix support now. I'm just not that good in all the compile magic so I didn't dare to do so yet...

Safihre avatar Feb 09 '24 19:02 Safihre

Just used pre-compiled x86_64 linux binary from 7zip website (7zzs) - replaced local 7za from /var/packages/sabnzbd/target/bin/7za, but still permissions are wrong after unpacking.


ash-4.4# 7za

7-Zip (z) 24.00 (x64) : Copyright (c) 1999-2024 Igor Pavlov : 2024-01-30
 64-bit locale=en_US.utf8 Threads:4 OPEN_MAX:1024, ASM

ash-4.4# ls -l 7za
-rwxr-xr-x 1 sc-sabnzbd synocommunity 3722360 Jan 30 18:57 7za

Maybe some additional arguments needs to be passed to popen from sabnzbd?

Also I think this issue would be quite easy to fix with post-unpack-script that will simply do chmod 666 to all the content in the unpacking directory, but I don't know how to write that kind of scripts :(

JustAndreww avatar Feb 09 '24 19:02 JustAndreww

SABnzbd can do that (Config > Folders (enable Advanced Settings) > Permissions), however, this breaks Synology persmission system so that's why it's disabled by default.

If you can find which command line argument it is, I can always add it.

Safihre avatar Feb 09 '24 19:02 Safihre

Thank you for the hint!

I will stick to that solution at the moment. 755 permissions looks OK to my use-case and as a temporary workaround.

JustAndreww avatar Feb 09 '24 19:02 JustAndreww

Is is a bug in p7zip. Unfortunately the version used by SynoCommunity is 16.02, where the 16 stands for 2016. p7zip hasn't been updated since. We could just try to compile the official 7zip (without the "p"), since it has good Unix support now. I'm just not that good in all the compile magic so I didn't dare to do so yet...

I can have a look at that in the coming days if it helps.

th0ma7 avatar Feb 09 '24 23:02 th0ma7

Unfortunately it seems the new one also doesn't have any special flags (like unrar) to not set permissions..

Safihre avatar Feb 10 '24 06:02 Safihre

As far as I know does 7zip not set any permission at all. So the file will get the default permission of the process running Sabnzbd depending on where the file is put.

If you need other permissions then you should make sure the location of the file is on a "shared folder" and the "system internal user" that sabnzbd is using must have read/write access there.

And the user from your Windows client should also have permissions there to be able to access them..

BenjV avatar Feb 10 '24 11:02 BenjV

@BenjV this is not true for 7zip. Just use this test nzb. It's a zip, which will use 7zip to unpack. https://github.com/sabnzbd/sabnzbd.github.io/blob/master/tests/test_zip.nzb

Safihre avatar Feb 10 '24 13:02 Safihre

You can only test this on a Synology and with SabNzbd, any other test will be useless.

Within a zip there is no permission information so 7zip cannot set any meaninfull permissions. So the permission the files get depend on the system setting where 7zip is running.

And on a Synology that will only result in a workable way if you put that files on a location where another user can access them as I explained.

BenjV avatar Feb 12 '24 08:02 BenjV

@BenjV seriously, did you test it on your Synology? Because it does not work. Show us a screenshot of the permissions that you get for a zip file downloaded and extracted with SABnzbd. It seems 7zip sets permissions anyway.

Safihre avatar Feb 12 '24 08:02 Safihre

@Safihre, I've done a few tests with the test nzb you provided. It normally downloads and extracts to downloads/complete/test_zip, with permissions like these:

$ ls -all
total 4380
drwxrwxrwx+ 1 sc-sabnzbd synocommunity      24 Feb 12 08:22 .
drwxrwxrwx+ 1 sc-sabnzbd synocommunity      20 Feb 12 08:22 ..
-rw-------  1 sc-sabnzbd synocommunity 4482652 Nov 18  2016 test_zip.bin

If I download only I get the folder with a file 8288838_ZIP_FILE.zip in it. Taking a look at the details, it looks like a fairly simple archive:

$ 7za l -slt 8288838_ZIP_FILE.zip 

7-Zip (a) [64] 16.02 : Copyright (c) 1999-2016 Igor Pavlov : 2016-05-21
p7zip Version 16.02 (locale=en_US.utf8,Utf16=on,HugeFiles=on,64 bits,2 CPUs x64)

Scanning the drive for archives:
1 file, 3624126 bytes (3540 KiB)

Listing archive: 8288838_ZIP_FILE.zip

--
Path = 8288838_ZIP_FILE.zip
Type = zip
Physical Size = 3624126

----------
Path = testfile.bin
Folder = -
Size = 4482652
Packed Size = 3623968
Modified = 2016-11-18 11:00:48
Created = 2017-03-15 08:26:47
Accessed = 2017-03-15 08:26:47
Attributes = AC
Encrypted = -
Comment = 
CRC = DC714B31
Method = Deflate
Host OS = FAT
Version = 20
Volume Index = 0

A manual extraction looks like this:

$ sudo -u sc-sabnzbd 7za x 8288838_ZIP_FILE.zip

7-Zip (a) [64] 16.02 : Copyright (c) 1999-2016 Igor Pavlov : 2016-05-21
p7zip Version 16.02 (locale=en_US.utf8,Utf16=on,HugeFiles=on,64 bits,2 CPUs x64)

Scanning the drive for archives:
1 file, 3624126 bytes (3540 KiB)

Extracting archive: 8288838_ZIP_FILE.zip
--
Path = 8288838_ZIP_FILE.zip
Type = zip
Physical Size = 3624126

Everything is Ok

Size:       4482652
Compressed: 3624126
$ ls -all
total 7920
drwxrwxrwx+ 1 sc-sabnzbd synocommunity      64 Feb 12 08:14 .
drwxrwxrwx+ 1 sc-sabnzbd synocommunity      20 Feb 12 07:54 ..
-rw-rw-rw-  1 sc-sabnzbd synocommunity 3624126 Feb 12 07:54 8288838_ZIP_FILE.zip
-rwx------  1 sc-sabnzbd synocommunity 4482652 Nov 18  2016 testfile.bin

As you can see the permissions are defaulting to the running user only. I can however override this forcibly like this:

$ sudo -u sc-sabnzbd sh -c 'umask 111 && 7za x 8288838_ZIP_FILE.zip'

7-Zip (a) [64] 16.02 : Copyright (c) 1999-2016 Igor Pavlov : 2016-05-21
p7zip Version 16.02 (locale=en_US.utf8,Utf16=on,HugeFiles=on,64 bits,2 CPUs x64)

Scanning the drive for archives:
1 file, 3624126 bytes (3540 KiB)

Extracting archive: 8288838_ZIP_FILE.zip
--
Path = 8288838_ZIP_FILE.zip
Type = zip
Physical Size = 3624126

Everything is Ok

Size:       4482652
Compressed: 3624126
$ ls -all
total 7920
drwxrwxrwx+ 1 sc-sabnzbd synocommunity      64 Feb 12 08:20 .
drwxrwxrwx+ 1 sc-sabnzbd synocommunity      20 Feb 12 07:54 ..
-rw-rw-rw-  1 sc-sabnzbd synocommunity 3624126 Feb 12 07:54 8288838_ZIP_FILE.zip
-rw-rw-rw-  1 sc-sabnzbd synocommunity 4482652 Nov 18  2016 testfile.bin

It's not the best method but I couldn't see any permissions setting options for 7za on first glance.

mreid-tt avatar Feb 12 '24 12:02 mreid-tt

Permissions would then follow the umask directive from the user shell environment...

th0ma7 avatar Feb 12 '24 13:02 th0ma7

So how would we choose the umask?

Safihre avatar Feb 12 '24 14:02 Safihre

In my opinion, if 7za can't set permissions directly through arguments, it's more practical to use existing post-download methods. Introducing another workaround (like umask) doesn't seem necessary to me.

mreid-tt avatar Feb 12 '24 14:02 mreid-tt

I thought so indeed.. Too bad 7zip doesn't have a parameter for it. Unrar does have a specific parameter to not set permissions (which we use).

Safihre avatar Feb 12 '24 14:02 Safihre

@Safihre, based on my understanding, it seems that this issue is not exclusive to Synology but rather could potentially affect any Linux implementation. Would you concur with this assessment? If so, it implies that the problem lies upstream but is not inherently a flaw within SABnzbd itself. It is due to a dependency constraint preventing a straightforward resolution, though there exists a workaround to mitigate the issue. Would you agree with this interpretation?

mreid-tt avatar Feb 12 '24 14:02 mreid-tt

Agreed. Not Synology specific. The complicating factor here is that if Permissions is set in SABnzbd Config Folders, it uses chmod and breaks the Synology permissions. But that's all we can do about it.

Safihre avatar Feb 12 '24 14:02 Safihre

From Wikipedia on 7z:

The 7z format does not store filesystem permissions (such as UNIX owner/group permissions or NTFS ACLs), and hence can be inappropriate for backup/archival purposes.

mreid-tt avatar Feb 12 '24 14:02 mreid-tt

So how would we choose the umask?

umask is rather interesting... consider the following on my linux, default behavior:

$ touch test
$ ls -la test
-rw-rw-r-- 1 th0ma7 th0ma7 0 fév 12 21:39 test

Mode is u+rw, g+rw, a+r which would be described as 0664 with chmod command using octal mode. Likewise setting it rwx to all would be:

$ chmod 0777 test
$ ls -al test
-rwxrwxrwx 1 th0ma7 th0ma7 0 fév 12 21:39 test

Now what about umask you ask... well umask runs in octal mode where you mask certain bits. For instance, a normal u+rw, g+rw, a+r ,described as 0664, would mean having a umask of 0113 such as:

  0777
- 0664
--------
  0113

So using this principle:

$ umask 0113   <-- equivalent of chmod 0664, or considering 0777-0664 = 0113
$ rm test
$ touch test
$ ls -la test
-rw-rw-r-- 1 th0ma7 th0ma7 0 fév 12 21:42 test

umask sets the file creation mode of, well, files to be created by said program for which its environment was set with a certain umask. Now wanting files to be rw to all would be:

$ umask 0111   <-- equivalent of chmod 0666, or considering 0777-0666 = 0111
$ rm test
$ touch test
$ ls -la test
-rw-rw-rw- 1 th0ma7 th0ma7 0 fév 12 21:42 test

and so on...

Now, going back to sabnzbd, adding a simple umask 0113 (for 0664) or umask 0111 (for 0666) in the startup script may resolve the issue. Again, this may have a different behavior when used in conjunction with ACL ... but worth testing.

th0ma7 avatar Feb 13 '24 02:02 th0ma7

You are all forgetting that Synology has implemented its own permission system based on the ACL's and not on the default Linux permissions.

BenjV avatar Feb 13 '24 10:02 BenjV

We are very well aware. So what do you propose we do @BenjV?

Safihre avatar Feb 13 '24 10:02 Safihre

I already explained it.

The target where 7zip will place the files must be on a "shared folder" and that shared folder must be granted read/write privs for the "system internal user" which is running 7zip so in the case 7zip is spawned by SabNzbd the system intenal user of SabNzbd.

And if you need access to those files by a user from the network, that user must be granted privs also. And all this granting must be done via the DSM gui otherwise it will not function correctly.

BenjV avatar Feb 13 '24 12:02 BenjV

@BenjV, isn't that the same advice we already provide in the Permission Management wiki? If so, then there is nothing to address in the package or the upstream software. If not, then I am unclear on how that addresses the issue raised by the user.

mreid-tt avatar Feb 13 '24 12:02 mreid-tt

@BenjV Do you really not read anything posted by other users here? @mreid-tt showed that 7zip forcedly sets permissions, so it's an upstream problem of 7zip that always sets permissions and breaks the usual DSM permission system.

Safihre avatar Feb 13 '24 12:02 Safihre

And if i may add, i played with 7zip to replace p7zip and wow ... Cross-compiling this isn't easy. I wasn't able to get far, even following arch linux recipe.

th0ma7 avatar Feb 13 '24 13:02 th0ma7

Now, going back to sabnzbd, adding a simple umask 0113 (for 0664) or umask 0111 (for 0666) in the startup script may resolve the issue. Again, this may have a different behavior when used in conjunction with ACL ... but worth testing.

hey @th0ma7, interesting findings. When you mean the startup script do you mean a modification to this line?

https://github.com/SynoCommunity/spksrc/blob/e5836de308643db8e048670cc461254c5244c169/spk/sabnzbd/src/service-setup.sh#L14

mreid-tt avatar Feb 13 '24 13:02 mreid-tt

Yes. But testing is needed considering both acl and how p7zip works.

th0ma7 avatar Feb 13 '24 15:02 th0ma7

Considering this is the first report of this problem even though the problem has existed for years, should we really be adding that?

Safihre avatar Feb 13 '24 15:02 Safihre