Unpacker loop problem

[grogcw]

What version are you using? 2.0.46 Wat OS are you running? Debian 12 Are you using Docker or as a service? As service - Launch command : /root/.dotnet/dotnet /home/rdtc_user/rdtc/RdtClient.Web.dll Which debrid provider are you using? RD Please attach a log file here with the log setting set to debug

[Anonimized log]

[21:12:30 DBG] Finished updating torrent info from Real-Debrid, next update in 5 seconds [21:12:31 DBG] Found 1 Aria2 downloads [21:12:31 DBG] Aria2 download found as complete 723xxxxxxxxxxxx5c [21:12:31 DBG] Remove download https://22.download.real-debrid.com/d/MHI2xxxxxxxxx/RD.rar 723xxxxxxxxxxxx5c from Aria2 [21:12:31 DBG] Finished updating Aria2 status [21:12:31 DBG] Processing 1 completed downloads [21:12:31 DBG] Processing download for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent R (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Download finished successfully for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Removed from ActiveDownloadClients for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Processing 1 torrents [21:12:31 DBG] Starting unpack for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Found file name RD.rar for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Setting unpack path to /media/Plex/@AUTO_DL/sonarr for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Starting unpack for download RD.rar. Completed: -2147483648%, avg speed: 0bytes/s (66b25e99-bf76-45bf-xxxx-645d8e8ece77) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:31 DBG] TorrentRunner Tick End (took 5869ms) [21:12:32 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:32 DBG] Processing 1 torrents [21:12:32 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:32 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:33 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:33 DBG] Processing 1 torrents [21:12:33 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:33 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:34 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:34 DBG] Processing 1 torrents [21:12:34 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:34 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:35 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:35 DBG] Processing 1 torrents [21:12:35 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:35 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:35 DBG] Updating torrent info from Real-Debrid [21:12:36 DBG] Finished updating torrent info from Real-Debrid, next update in 5 seconds [21:12:36 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:36 DBG] Processing 1 torrents [21:12:36 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:36 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:37 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:37 DBG] Processing 1 torrents [21:12:37 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:37 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:38 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:38 DBG] Processing 1 torrents [21:12:38 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:38 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:39 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:39 DBG] Processing 1 torrents [21:12:39 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:39 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:40 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [21:12:40 DBG] Processing 1 torrents [21:12:40 DBG] Processing for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:40 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent RD (PIExxxxxxxxxE - downloaded 100%) (fbded7b0-108d-4d83-xxxx-f9f641cac43d) [21:12:41 DBG] Updating torrent info from Real-Debrid [21:12:41 DBG] Finished updating torrent info from Real-Debrid, next update in 5 seconds

---

Seems like the issue is an erroneous state of the download incoming from the downloader (tested with Internal and Aria2).

Raw files are sucessfully downloaded and imported to Radarr & Sonarr, but when it comes to decompress rar files, the unpack process is stuck, loops, and doesn't decompress any files. I haven't tested with zip files, tho.

I use the dotnet sdk v8.0.1 (lastest) installed via Microsoft's script on a Debian vm.

I tried reverting back to an "old" 2.0.43 version of the client, but the behavior is the same.

Maybe this is due to a breaking change in the sdk which I update from time to time.

Can you take a look at it ?

I can ginea pig things for you if needed.

Cheers !

[brackson]

Experiencing this same issue. I'm using Docker and Radarr. I'm using sshfs for my finished download directory, if that's relevant. Here's the output from my logs. I redacted the torrent name and IDs but it does this for all torrents I try.

What version are you using? rdtclient: 2.0.46, radarr: 5.1.3.8246 What OS are you running? OS: Ubuntu 22.04.3 LTS x86_64 Are you using Docker or as a service? Docker Which debrid provider are you using? real-debrid Please attach a log file here with the log setting set to debug

Logs

``` [23:25:37 DBG] Updating torrent info from Real-Debrid [23:25:37 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [23:25:37 DBG] Processing 1 torrents [23:25:37 DBG] Processing for torrent [redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:25:37 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent [redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:25:37 DBG] Finished updating torrent info from Real-Debrid, next update in 10 seconds [23:25:38 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [23:25:38 DBG] Processing 1 torrents [23:25:38 DBG] Processing for torrent [redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:25:38 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent [redacted torrent name] (Fxxxxxxxxxxx2- downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) ... [23:28:41 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks [23:28:41 DBG] Processing 1 completed unpacks [23:28:41 DBG] Unpack finished successfully for download [redacted torrent name].rar. Completed: -2147483648%, avg speed: 0bytes/s (d8283519-083f-45fa-xxxx-96e14d008e14) for torrent [redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:28:41 DBG] Removed from ActiveUnpackClients for download [redacted torrent name].rar. Completed: -2147483648%, avg speed: 0bytes/s (d8283519-083f-45fa-xxxx-96e14d008e14) for torrent[redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:28:41 DBG] Processing 1 torrents [23:28:41 DBG] Processing for torrent [redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:28:41 DBG] All downloads complete, marking torrent as complete for torrent [redacted torrent name] (Fxxxxxxxxxxx2- downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:28:41 DBG] Not removing torrents or files for torrent [redacted torrent name] (Fxxxxxxxxxxx2 - downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:28:47 DBG] Updating torrent info from Real-Debrid [23:28:47 DBG] Finished updating torrent info from Real-Debrid, next update in 10 seconds [23:28:57 DBG] Updating torrent info from Real-Debrid [23:28:58 DBG] Finished updating torrent info from Real-Debrid, next update in 10 seconds [23:29:08 DBG] Updating torrent info from Real-Debrid [23:29:09 DBG] Finished updating torrent info from Real-Debrid, next update in 10 seconds [23:29:19 DBG] Updating torrent info from Real-Debrid [23:29:19 DBG] Finished updating torrent info from Real-Debrid, next update in 10 seconds [23:29:27 DBG] Method: GET, Path: /api/v2/app/webapiVersion [23:29:27 DBG] Method: GET, Path: /api/v2/app/preferences [23:29:27 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=radarr [23:29:29 DBG] Updating torrent info from Real-Debrid [23:29:30 DBG] Finished updating torrent info from Real-Debrid, next update in 10 seconds [23:29:31 DBG] Method: POST, Path: /api/v2/torrents/delete, Form: hashes: 479aedb013e36e4f02addf9c1e18369xxxxxxxx, deleteFiles: true [23:29:31 DBG] Delete 479aedb013e36e4f02addf9c1e18369xxxxxxxx [23:29:31 DBG] Delete 479aedb013e36e4f02addf9c1e18369xxxxxxxx [23:29:32 DBG] Removing torrents from Real-Debrid and Real-Debrid Client, no files [23:29:32 DBG] Deleting for torrent [redacted torrent name] (Fxxxxxxxxxxx2- downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:29:32 DBG] Deleting RdtClient data for torrent [redacted torrent name] (Fxxxxxxxxxxx2- downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) [23:29:32 DBG] Deleting RealDebrid Torrent for torrent [redacted torrent name] (Fxxxxxxxxxxx2- downloaded 100%) (e72f90c8-0552-4491-xxxx-094d67d14504) ```

The torrent is unpacked before the loop happens. Radarr eventually pulls it in (pretty sure it's after the Refresh Monitored Downloads task runs).

This is a great project! Let me know if you'd like help debugging or patching :-)

[rogerfar]

Could you send me the actual torrent file or magnet through a pastebin?

[grogcw]

Here's one : https://pastebin.com/fW51pFgf

[rogerfar]

I tried reproducing it but couldn't, is this still an issue?

[grogcw]

I'm using 2.0.59, and yes, it's still an issue. I'll update to .61 to check if the issue persists.

[grogcw]

I tested the 2.0.61, and yes, the issue is still the same.

Here's the truncated and redacted log :

[21:32:50 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:32:50 DBG] Method: GET, Path: /api/v2/app/preferences
[21:32:50 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=radarr
[21:32:57 DBG] Method: GET, Path: /api/v2/app/preferences
[21:32:57 DBG] Method: POST, Path: /api/v2/torrents/add, Form: urls: magnet:.................., category: sonarr, paused: False
[21:32:57 DBG] Add magnet sonarr
[21:32:58 DBG] Adding ................. magnet link magnet:.............. for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:58 DBG] Processing 1 torrents
[21:32:58 DBG] Processing for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:58 DBG] Selecting files for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:59 DBG] Seleting files for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:59 DBG] Determining which files are already available on RealDebrid for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:59 DBG] Found 4/4 available files on RealDebrid for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:59 DBG] Selecting 4/4 files for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:59 DBG] Selecting files:
[21:32:59 DBG] 1: /.....txt (71b)
[21:32:59 DBG] 2: /.....txt (718b)
[21:32:59 DBG] 3: /.....mkv (1490047127b)
[21:32:59 DBG] 4: /.....nfo (986b)
[21:32:59 DBG]  for torrent ............ (......... - waiting_files_selection 0%) (...................)
[21:32:59 DBG] Updating torrent info from debrid provider
[21:32:59 DBG] Finished updating torrent info from debrid provider, next update in 30 seconds
[21:33:00 DBG] Processing 1 torrents
[21:33:00 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:00 DBG] Creating downloads for torrent ............ (......... - downloaded 100%) (...................)
[21:33:00 DBG] Found 1 links for torrent ............ (......... - downloaded 100%) (...................)
[21:33:00 DBG] https://real-debrid.com/d/................... for torrent ............ (......... - downloaded 100%) (...................)
[21:33:00 DBG] Torrent has 4 selected files out of 4 files, found 1 links, torrent ended: 12/18/2023 5:10:37 AM +01:00 for torrent ............ (......... - downloaded 100%) (...................)
[21:33:00 DBG] Waiting to see if more links appear, checked for 4983743.4208369 seconds for torrent ............ (......... - downloaded 100%) (...................)
[21:33:00 DBG] Waited long enough for torrent ............ (......... - downloaded 100%) (...................)
[21:33:01 DBG] Processing 1 torrents
[21:33:01 DBG] Processing to download for download https://real-debrid.com/d/.................... Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:01 DBG] Unrestricting links for download https://real-debrid.com/d/.................... Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:01 DBG] Unrestricting link for download https://real-debrid.com/d/.................... Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:01 DBG] Setting download path to /my_own_localpath/sonarr for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:01 DBG] Starting download for download ....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:01 DBG] Starting download of https://23.download.real-debrid.com/d/.....rar, writing to path: /my_own_localpath/sonarr/<archive_name>/.....rar (on aria2: /my_own_localpath/sonarr/<archive_name>/.....rar), fileName: ......rar
[21:33:01 DBG] Added download to Aria2, received ID ...................
[21:33:01 DBG] Download with ID ................... found in Aria2
[21:33:02 DBG] Received ID ................... for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:02 DBG] Marking download as started for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:03 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:03 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:03 DBG] TorrentRunner Tick End (took 2019ms)
[21:33:04 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:33:04 DBG] Method: GET, Path: /api/v2/app/preferences
[21:33:04 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=sonarr
[21:33:04 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:04 DBG] Updating Aria2 status
[21:33:04 DBG] Found 1 Aria2 downloads
[21:33:04 DBG] Finished updating Aria2 status
[21:33:04 DBG] Processing 1 torrents
[21:33:04 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:04 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:05 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:05 DBG] Updating Aria2 status
[21:33:11 DBG] Found 1 Aria2 downloads
[21:33:11 DBG] Finished updating Aria2 status
[21:33:11 DBG] Processing 1 torrents
[21:33:11 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:11 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:11 DBG] TorrentRunner Tick End (took 5827ms)
[21:33:12 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:12 DBG] Updating Aria2 status
[21:33:12 DBG] Found 1 Aria2 downloads
[21:33:12 DBG] Finished updating Aria2 status
[21:33:12 DBG] Processing 1 torrents
[21:33:12 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:12 DBG] Waiting for downloads to complete. 0/1 complete (4%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:13 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:13 DBG] Updating Aria2 status
[21:33:13 DBG] Found 1 Aria2 downloads
[21:33:13 DBG] Finished updating Aria2 status
[21:33:13 DBG] Processing 1 torrents
[21:33:13 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:13 DBG] Waiting for downloads to complete. 0/1 complete (11%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:14 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:14 DBG] Updating Aria2 status
[21:33:14 DBG] Found 1 Aria2 downloads
[21:33:14 DBG] Finished updating Aria2 status
[21:33:14 DBG] Processing 1 torrents
[21:33:14 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:14 DBG] Waiting for downloads to complete. 0/1 complete (19%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:15 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:15 DBG] Updating Aria2 status
[21:33:15 DBG] Found 1 Aria2 downloads
[21:33:15 DBG] Finished updating Aria2 status
[21:33:15 DBG] Processing 1 torrents
[21:33:15 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:15 DBG] Waiting for downloads to complete. 0/1 complete (26%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:16 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:16 DBG] Updating Aria2 status
[21:33:16 DBG] Found 1 Aria2 downloads
[21:33:16 DBG] Finished updating Aria2 status
[21:33:16 DBG] Processing 1 torrents
[21:33:16 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:16 DBG] Waiting for downloads to complete. 0/1 complete (34%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:17 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:17 DBG] Updating Aria2 status
[21:33:17 DBG] Found 1 Aria2 downloads
[21:33:17 DBG] Finished updating Aria2 status
[21:33:17 DBG] Processing 1 torrents
[21:33:17 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:17 DBG] Waiting for downloads to complete. 0/1 complete (41%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:18 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:18 DBG] Updating Aria2 status
[21:33:18 DBG] Found 1 Aria2 downloads
[21:33:18 DBG] Finished updating Aria2 status
[21:33:18 DBG] Processing 1 torrents
[21:33:18 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:18 DBG] Waiting for downloads to complete. 0/1 complete (49%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:19 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:19 DBG] Updating Aria2 status
[21:33:19 DBG] Found 1 Aria2 downloads
[21:33:19 DBG] Finished updating Aria2 status
[21:33:19 DBG] Processing 1 torrents
[21:33:19 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:19 DBG] Waiting for downloads to complete. 0/1 complete (56%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:20 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:20 DBG] Updating Aria2 status
[21:33:20 DBG] Found 1 Aria2 downloads
[21:33:20 DBG] Finished updating Aria2 status
[21:33:20 DBG] Processing 1 torrents
[21:33:20 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:20 DBG] Waiting for downloads to complete. 0/1 complete (63%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:21 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:21 DBG] Updating Aria2 status
[21:33:21 DBG] Found 1 Aria2 downloads
[21:33:21 DBG] Finished updating Aria2 status
[21:33:21 DBG] Processing 1 torrents
[21:33:21 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:21 DBG] Waiting for downloads to complete. 0/1 complete (70%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:22 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:22 DBG] Updating Aria2 status
[21:33:22 DBG] Found 1 Aria2 downloads
[21:33:22 DBG] Finished updating Aria2 status
[21:33:22 DBG] Processing 1 torrents
[21:33:22 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:22 DBG] Waiting for downloads to complete. 0/1 complete (77%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:23 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:23 DBG] Updating Aria2 status
[21:33:23 DBG] Found 1 Aria2 downloads
[21:33:23 DBG] Finished updating Aria2 status
[21:33:23 DBG] Processing 1 torrents
[21:33:23 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:23 DBG] Waiting for downloads to complete. 0/1 complete (85%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:24 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:24 DBG] Updating Aria2 status
[21:33:24 DBG] Found 1 Aria2 downloads
[21:33:24 DBG] Finished updating Aria2 status
[21:33:24 DBG] Processing 1 torrents
[21:33:24 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:24 DBG] Waiting for downloads to complete. 0/1 complete (92%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:25 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:25 DBG] Updating Aria2 status
[21:33:25 DBG] Found 1 Aria2 downloads
[21:33:25 DBG] Finished updating Aria2 status
[21:33:25 DBG] Processing 1 torrents
[21:33:25 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:25 DBG] Waiting for downloads to complete. 0/1 complete (99%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:26 DBG] TorrentRunner Tick Start, 1 active downloads, 0 active unpacks
[21:33:26 DBG] Updating Aria2 status
[21:33:29 DBG] Updating torrent info from debrid provider
[21:33:29 DBG] Finished updating torrent info from debrid provider, next update in 30 seconds
[21:33:32 DBG] Found 1 Aria2 downloads
[21:33:32 DBG] Aria2 download found as complete ...................
[21:33:32 DBG] Remove download https://23.download.real-debrid.com/d/.....rar ................... from Aria2
[21:33:32 DBG] Finished updating Aria2 status
[21:33:32 DBG] Processing 1 completed downloads
[21:33:32 DBG] Processing download for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Download finished successfully for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Removed from ActiveDownloadClients for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Processing 1 torrents
[21:33:32 DBG] Starting unpack for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Found file name .....rar for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Setting unpack path to /my_local_path/sonarr for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Starting unpack for download .....rar. Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:32 DBG] TorrentRunner Tick End (took 5909ms)
[21:33:33 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:33 DBG] Processing 1 torrents
[21:33:33 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:33 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:34 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:34 DBG] Processing 1 torrents
[21:33:34 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:34 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:35 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:35 DBG] Processing 1 torrents
[21:33:35 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:35 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:36 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:36 DBG] Processing 1 torrents
[21:33:36 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:36 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:37 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:37 DBG] Processing 1 torrents
[21:33:37 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:37 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:38 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:38 DBG] Processing 1 torrents
[21:33:38 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:38 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:39 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:39 DBG] Processing 1 torrents
[21:33:39 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:39 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:40 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:40 DBG] Processing 1 torrents
[21:33:40 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:40 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:41 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:41 DBG] Processing 1 torrents
[21:33:41 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:41 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:42 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:42 DBG] Processing 1 torrents
[21:33:42 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:42 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:43 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:43 DBG] Processing 1 torrents
[21:33:43 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:43 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:44 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:44 DBG] Processing 1 torrents
[21:33:44 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:44 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:45 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:45 DBG] Processing 1 torrents
[21:33:45 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:45 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:46 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:46 DBG] Processing 1 torrents
[21:33:46 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:46 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:47 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:47 DBG] Processing 1 torrents
[21:33:47 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:47 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:47 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:33:47 DBG] Method: GET, Path: /api/v2/app/preferences
[21:33:47 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=sonarr
[21:33:48 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:48 DBG] Processing 1 torrents
[21:33:48 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:48 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:48 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:33:48 DBG] Method: GET, Path: /api/v2/app/preferences
[21:33:48 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=sonarr
[21:33:49 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:49 DBG] Processing 1 torrents
[21:33:49 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:49 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:50 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:50 DBG] Processing 1 torrents
[21:33:50 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:50 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:51 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:51 DBG] Processing 1 torrents
[21:33:51 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:51 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:52 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:52 DBG] Processing 1 torrents
[21:33:52 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:52 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:53 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:53 DBG] Processing 1 torrents
[21:33:53 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:53 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:54 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:54 DBG] Processing 1 torrents
[21:33:54 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:54 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:55 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:55 DBG] Processing 1 torrents
[21:33:55 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:55 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:56 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:56 DBG] Processing 1 torrents
[21:33:56 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:56 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:57 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:57 DBG] Processing 1 torrents
[21:33:57 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:57 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:58 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:58 DBG] Processing 1 torrents
[21:33:58 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:58 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:59 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:33:59 DBG] Processing 1 torrents
[21:33:59 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:33:59 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:33:59 DBG] Updating torrent info from debrid provider
[21:34:00 DBG] Finished updating torrent info from debrid provider, next update in 30 seconds
[21:34:00 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:00 DBG] Processing 1 torrents
[21:34:00 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:00 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:01 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:01 DBG] Processing 1 torrents
[21:34:01 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:01 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:02 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:02 DBG] Processing 1 torrents
[21:34:02 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:02 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:03 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:03 DBG] Processing 1 torrents
[21:34:03 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:03 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:04 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:04 DBG] Processing 1 torrents
[21:34:04 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:04 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:05 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:05 DBG] Processing 1 torrents
[21:34:05 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:05 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:06 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:06 DBG] Processing 1 torrents
[21:34:06 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:06 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:07 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:07 DBG] Processing 1 torrents
[21:34:07 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:07 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:08 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:08 DBG] Processing 1 torrents
[21:34:08 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:08 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:09 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:09 DBG] Processing 1 torrents
[21:34:09 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:09 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:10 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:10 DBG] Processing 1 torrents
[21:34:10 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:10 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:11 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:11 DBG] Processing 1 torrents
[21:34:11 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:11 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:12 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:12 DBG] Processing 1 torrents
[21:34:12 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:12 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:13 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:13 DBG] Processing 1 torrents
[21:34:13 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:13 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:14 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:14 DBG] Processing 1 torrents
[21:34:14 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:14 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:15 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:15 DBG] Processing 1 torrents
[21:34:15 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:15 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:16 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:16 DBG] Processing 1 torrents
[21:34:16 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:16 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:17 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:17 DBG] Processing 1 torrents
[21:34:17 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:17 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:18 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:18 DBG] Processing 1 torrents
[21:34:18 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:18 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:19 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:19 DBG] Processing 1 torrents
[21:34:19 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:19 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:19 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:34:20 DBG] Method: GET, Path: /api/v2/app/preferences
[21:34:20 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=radarr
[21:34:20 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:20 DBG] Processing 1 torrents
[21:34:20 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:20 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:21 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:21 DBG] Processing 1 torrents
[21:34:21 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:21 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:22 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:22 DBG] Processing 1 torrents
[21:34:22 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:22 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:23 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:23 DBG] Processing 1 torrents
[21:34:23 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:23 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:24 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:24 DBG] Processing 1 torrents
[21:34:24 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:24 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:25 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:25 DBG] Processing 1 torrents
[21:34:25 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:25 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:26 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:26 DBG] Processing 1 torrents
[21:34:26 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:26 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:27 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:27 DBG] Processing 1 torrents
[21:34:27 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:27 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:28 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:28 DBG] Processing 1 torrents
[21:34:28 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:28 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:29 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:29 DBG] Processing 1 torrents
[21:34:29 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:29 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:30 DBG] Updating torrent info from debrid provider
[21:34:30 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:30 DBG] Processing 1 torrents
[21:34:30 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:30 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:30 DBG] Finished updating torrent info from debrid provider, next update in 30 seconds
[21:34:31 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:31 DBG] Processing 1 torrents
[21:34:31 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:31 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:32 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:32 DBG] Processing 1 torrents
[21:34:32 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:32 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:33 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:33 DBG] Processing 1 torrents
[21:34:33 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:33 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:34 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:34 DBG] Processing 1 torrents
[21:34:34 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:34 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:35 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:35 DBG] Processing 1 torrents
[21:34:35 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:35 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:36 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:36 DBG] Processing 1 torrents
[21:34:36 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:36 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:37 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:37 DBG] Processing 1 torrents
[21:34:37 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:37 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:38 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:34:38 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:34:38 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:34:38 DBG] Method: GET, Path: /api/v2/app/preferences
[21:34:38 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=sonarr
[21:34:38 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:38 DBG] Processing 1 torrents
[21:34:38 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:38 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:39 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:39 DBG] Processing 1 torrents
[21:34:39 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:39 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)
[21:34:40 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:34:40 DBG] Processing 1 torrents
[21:34:40 DBG] Processing for torrent ............ (......... - downloaded 100%) (...................)
[21:34:40 DBG] Waiting for downloads to complete. 0/1 complete (0%) for torrent ............ (......... - downloaded 100%) (...................)

[....]

[21:44:46 DBG] Method: POST, Path: /api/v2/torrents/delete, Form: hashes: .................., deleteFiles: true
[21:44:46 DBG] Delete ..................
[21:44:46 DBG] Delete ..................
[21:44:46 DBG] Removing torrents from debrid provider and RDT-Client, no files
[21:44:46 DBG] Deleting for torrent ............ (......... - downloaded 100%) (...................)
[21:44:46 DBG] Cancelling unpack for download .....rar. Completed: 0%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)
[21:44:46 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:44:46 DBG] Deleting RdtClient data for torrent ............ (......... - downloaded 100%) (...................)
[21:44:46 DBG] Deleting RealDebrid Torrent for torrent ............ (......... - downloaded 100%) (...................)
[21:44:47 DBG] TorrentRunner Tick Start, 0 active downloads, 1 active unpacks
[21:44:50 DBG] Method: GET, Path: /api/v2/app/webapiVersion
[21:44:50 DBG] Method: GET, Path: /api/v2/app/preferences
[21:44:50 DBG] Method: GET, Path: /api/v2/torrents/info, QueryString: ?category=radarr

The machine's configuration is the same, no package upgrades, no updates apart from the RDT-Client's version.

I did add the same torrent from the pastebin above and then deleted the download from sonarr's webgui after a few minutes. The log has been still whereas the TorrentRunner was looping with the version 2.0.59, so there's an improvement.

I think the problem lies in the percentage being reported erroneously in this line : (but I might be wrong) [21:33:01 DBG] Processing to download for download https://real-debrid.com/d/.................... Completed: -2147483648%, avg speed: 0bytes/s (...................) for torrent ............ (......... - downloaded 100%) (...................)

[rogerfar]

Are you saying it never completes the unpack process? It doesn't show a progress anymore because you can't have progress AND cancellations.

What is the CPU like when it's unpacking?

[grogcw]

I'm actually witnessing that the unpack process doesn't output anything file-wise and that it's stuck in a loop as you could see in the log, so I guess it never starts rather than completes :)

The CPU usage is showing nearly 0% while stuck in this loop. All threads are reported in a "sleep" state, except for one that is in a "Disk sleep" state either when the unpacker process is supposedly running or not, no difference here.

I guess you could you try implementing a call to unrar x <file> or unzip <file> to check if the problems lies in the Unpacker library or in the process, if I read the code correclty.

[rogerfar]

That's a lot of threads though, shouldn't have that many.

But just to be clear, does it always happen with the same torrent, or sporadic?

[grogcw]

Every torrent leading to .rar or .zip files is having this trouble, I just fetch the same to check if the issue remains as I know which one to look for.

Just by pure interest, how many threads should be reported ?

[rogerfar]

Hard to say honestly. Is this still an issue?

[grogcw]

Sadly, yes, the issue remains with the v2.0.6.9

[rogerfar]

Do you have a few magnets that show this behaviour?

[grogcw]

As previously mentioned and linked, I use a static torrent to test the behavior, but any torrent grabbed that has additional files in it (rendering a .rar or .zip package) gets stuck, even with 2.0.6.9

[rogerfar]

I tried the torrents you posted but they're not packed, so they're not experiencing this issue. Over time I haven't seen this problem occur so I have never been able to reproduce it.

[grogcw]

Ho, I see...

You can have a go at this magnet then.

It's compressed when donwloaded via RealDebrid.

Note that if you manually trigger the download via the website, you'll have to check all the boxes when asked (sonarr has the same behaviour).

[rogerfar]

Right, so that magnet downloads Family.Guy.S22E14.1080p.HEVC.x265-MeGusta[TGx].rar as expected because I selected all files.

But then the unpacker in RDT unpacks it as normal and I end up with 4 files:

Do you see a different behaviour?

[grogcw]

Well, in fact, I do :

the compressed file is downloaded then the unpacker does nothing (or gets stuck, hard to tell) so I have to unpack manually and import manually the desired file.

The log has the same shape as already posted above.

[grogcw]

That being said, I just discovered a way to mitigate the problem by setting the minimum file size to download hence avoiding the pack process at RealDebrid and the unpack process locally BUT the unpacker problem remains unsolved in 2.0.7.3 running in Debian.

[grogcw]

As an update on this, I dug the sharpcompress repository, and found this issue https://github.com/adamhathcock/sharpcompress/issues/714 leading to this link https://documentation.help/WinRAR/HELPArcSolid.htm

I don't say it is the core problem I'm facing, but it might be related.

[Euskalgaming]

Unpacker could be supported to perform the decompression process. RDT client is unable to decompress anything.