Migrated Sonarr from Windows -> Linux. All working well except for completed download handling

Sonarr version (exact version): 2.0.0.5338
Mono version (if Sonarr is not running on Windows): 6.4.0.198
OS: CentOS 7

I know migration from Windows to Linux is not officially supported. Still hoping to get help in understanding what the issue is I’m facing :slight_smile:

I installed Sonarr clean on my Linux box, copied the app data directory over, and manually edited the sqlite database to update the root folder location and all series folder locations to be correct. Everything scanned and imported well, all looked good!

I found an episode missing and told Sonarr to download it via Automatic search. This works correctly, it sends the download request to qbittorrent-nox, and the download completes. However, after the download is complete Sonarr never seems to import it, just delete it from the queue. The downloaded file never gets put in the series’ folder.

In addition, while the download is happening I do not see the download in the Activity queue.

If I restart Sonarr, I can get one download to work properly with full Activity tracking, adding the file to the media folder, and all. Then deleting the file from the disk, rescanning the media folder, and then telling it to redownload the file results in the above behavior (downloads, doesn’t track it under Activity, deletes it at the end and doesn’t add it to the media folder).

Below is the trace log where sonarr sees the download and then deletes it when it’s complete. Any help in understanding what is going on is appreciated, thank you!

19-10-21 14:57:07.2|Trace|HttpClient|Res: [GET] http://localhost:80/query/preferences: 200.OK (4 ms)
19-10-21 14:57:07.2|Trace|HttpClient|Response content (1923 bytes): {"add_trackers":"","add_trackers_enabled":false,"alt_dl_limit":-1,"alt_up_limit":-1,"anonymous_mode":false,"autorun_enabled":true,"autorun_program":"/var/lib/plexmediaserver/send_email.sh \"%N\"","bypass_local_auth":true,"dht":true,"dl_limit":-1,"dont_count_slow_torrents":true,"dyndns_domain":"changeme.dyndns.org","dyndns_enabled":false,"dyndns_password":"","dyndns_service":0,"dyndns_username":"","enable_utp":true,"encryption":0,"export_dir":"","export_dir_fin":"","force_proxy":true,"incomplete_files_ext":false,"ip_filter_enabled":false,"ip_filter_path":"","ip_filter_trackers":false,"limit_tcp_overhead":false,"limit_utp_rate":true,"listen_port":8999,"locale":"en","lsd":true,"mail_notification_auth_enabled":false,"mail_notification_email":"russell@jonesmail.me","mail_notification_enabled":false,"mail_notification_password":"(removed)","mail_notification_smtp":"mail.jonesmail.me","mail_notification_ssl_enabled":true,"mail_notification_username":"(removed)","max_active_downloads":200,"max_active_torrents":200,"max_active_uploads":200,"max_connec":500,"max_connec_per_torrent":100,"max_ratio":0,"max_ratio_act":0,"max_ratio_enabled":true,"max_uploads":-1,"max_uploads_per_torrent":-1,"pex":true,"preallocate_all":false,"proxy_auth_enabled":true,"proxy_ip":"184.75.220.106","proxy_password":"(removed)","proxy_peer_connections":true,"proxy_port":1080,"proxy_type":4,"proxy_username":"(removed)","queueing_enabled":true,"random_port":false,"save_path":"/mnt/Torrents/Completed/","scan_dirs":{},"schedule_from_hour":8,"schedule_from_min":0,"schedule_to_hour":20,"schedule_to_min":0,"scheduler_days":0,"scheduler_enabled":false,"ssl_cert":"","ssl_key":"","temp_path":"/mnt/Torrents/Downloading/","temp_path_enabled":true,"up_limit":250,"upnp":true,"use_https":false,"web_ui_password":"(removed)","web_ui_port":80,"web_ui_upnp":false,"web_ui_username":"(removed)"}
19-10-21 14:57:07.3|Trace|HttpClient|Req: [GET] http://localhost:80/query/torrents?label=tv-sonarr&category=tv-sonarr
19-10-21 14:57:07.3|Trace|ConfigService|Using default config value for 'proxyenabled' defaultValue:'False'
19-10-21 14:57:07.3|Trace|HttpClient|Res: [GET] http://localhost:80/query/torrents?label=tv-sonarr&category=tv-sonarr: 200.OK (5 ms)
19-10-21 14:57:07.3|Trace|HttpClient|Response content (546 bytes): [{"added_on":1571687734,"category":"tv-sonarr","completion_on":1571687821,"dlspeed":1874986,"eta":18446744073709552000,"f_l_piece_prio":false,"force_start":false,"hash":"35f410312849c9ce9fb7adc7eea173ad63bff27b","name":"Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg]","num_complete":156,"num_incomplete":15,"num_leechs":1,"num_seeds":0,"priority":0,"progress":1,"ratio":0.0022189074757347138,"save_path":"/mnt/Torrents/Completed/","seq_dl":false,"size":771077350,"state":"uploading","super_seeding":false,"upspeed":1225}]
19-10-21 14:57:07.3|Debug|TrackedDownloadService|Tracking 'qbittorrent:Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg]': ClientState=Completed (readonly) SonarrStage=Imported Episode='Modern Family - S11E04 WEBDL-720p v1' OutputPath=/mnt/Torrents/Completed/Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg].
19-10-21 14:57:07.3|Trace|EventAggregator|Publishing TrackedDownloadRefreshedEvent
19-10-21 14:57:07.3|Trace|EventAggregator|TrackedDownloadRefreshedEvent -> QueueService
19-10-21 14:57:07.3|Trace|EventAggregator|Publishing QueueUpdatedEvent
19-10-21 14:57:07.3|Trace|EventAggregator|QueueUpdatedEvent -> QueueModule
19-10-21 14:57:07.3|Trace|EventAggregator|QueueUpdatedEvent <- QueueModule
19-10-21 14:57:07.3|Trace|EventAggregator|TrackedDownloadRefreshedEvent <- QueueService
19-10-21 14:57:07.3|Trace|CommandQueueManager|Updating command status
19-10-21 14:57:07.3|Trace|EventAggregator|Publishing CommandExecutedEvent
19-10-21 14:57:07.3|Trace|EventAggregator|CommandExecutedEvent -> TaskManager
19-10-21 14:57:07.3|Trace|TaskManager|Updating last run time for: NzbDrone.Core.Download.CheckForFinishedDownloadCommand
19-10-21 14:57:07.3|Trace|EventAggregator|CommandExecutedEvent <- TaskManager
19-10-21 14:57:07.3|Trace|EventAggregator|CommandExecutedEvent -> TaskModule
19-10-21 14:57:07.3|Trace|EventAggregator|CommandExecutedEvent <- TaskModule
19-10-21 14:57:07.3|Trace|CommandExecutor|CheckForFinishedDownloadCommand <- DownloadMonitoringService [00:00:00.0850360]
19-10-21 14:57:07.4|Trace|Http|Req: 105 [GET] /api/queue?sort_by=timeleft&order=asc
19-10-21 14:57:07.4|Trace|Http|Res: 105 [GET] /api/queue?sort_by=timeleft&order=asc: 200.OK (6 ms)
19-10-21 14:57:07.4|Debug|Api|[GET] /api/queue?sort_by=timeleft&order=asc: 200.OK (6 ms)
19-10-21 14:57:23.7|Trace|Http|Req: 106 [GET] /api/history?page=1&pageSize=15&sortKey=date&sortDir=desc
19-10-21 14:57:23.9|Trace|Http|Res: 106 [GET] /api/history?page=1&pageSize=15&sortKey=date&sortDir=desc: 200.OK (280 ms)
19-10-21 14:57:23.9|Debug|Api|[GET] /api/history?page=1&pageSize=15&sortKey=date&sortDir=desc: 200.OK (280 ms)
19-10-21 14:57:29.1|Trace|Http|Req: 107 [GET] /api/series?sort_by=sortTitle&order=asc
19-10-21 14:57:29.3|Trace|Http|Res: 107 [GET] /api/series?sort_by=sortTitle&order=asc: 200.OK (188 ms)
19-10-21 14:57:29.3|Debug|Api|[GET] /api/series?sort_by=sortTitle&order=asc: 200.OK (188 ms)
19-10-21 14:57:37.2|Trace|Scheduler|Pending Tasks: 0
19-10-21 14:58:07.2|Trace|Scheduler|Pending Tasks: 0
19-10-21 14:58:37.2|Trace|Scheduler|Pending Tasks: 1
19-10-21 14:58:37.2|Trace|CommandQueueManager|Publishing CheckForFinishedDownload
19-10-21 14:58:37.2|Trace|CommandQueueManager|Checking if command is queued or started: CheckForFinishedDownload
19-10-21 14:58:37.2|Trace|CommandQueueManager|Inserting new command: CheckForFinishedDownload
19-10-21 14:58:37.2|Trace|CommandExecutor|CheckForFinishedDownloadCommand -> DownloadMonitoringService
19-10-21 14:58:37.2|Trace|CommandQueueManager|Marking command as started: CheckForFinishedDownload
19-10-21 14:58:37.3|Trace|HttpClient|Req: [GET] http://localhost:80/query/preferences
19-10-21 14:58:37.3|Trace|ConfigService|Using default config value for 'proxyenabled' defaultValue:'False'
19-10-21 14:58:37.3|Trace|HttpClient|Res: [GET] http://localhost:80/query/preferences: 200.OK (4 ms)
19-10-21 14:58:37.3|Trace|HttpClient|Response content (1923 bytes): {"add_trackers":"","add_trackers_enabled":false,"alt_dl_limit":-1,"alt_up_limit":-1,"anonymous_mode":false,"autorun_enabled":true,"autorun_program":"/var/lib/plexmediaserver/send_email.sh \"%N\"","bypass_local_auth":true,"dht":true,"dl_limit":-1,"dont_count_slow_torrents":true,"dyndns_domain":"changeme.dyndns.org","dyndns_enabled":false,"dyndns_password":"","dyndns_service":0,"dyndns_username":"","enable_utp":true,"encryption":0,"export_dir":"","export_dir_fin":"","force_proxy":true,"incomplete_files_ext":false,"ip_filter_enabled":false,"ip_filter_path":"","ip_filter_trackers":false,"limit_tcp_overhead":false,"limit_utp_rate":true,"listen_port":8999,"locale":"en","lsd":true,"mail_notification_auth_enabled":false,"mail_notification_email":"russell@jonesmail.me","mail_notification_enabled":false,"mail_notification_password":"(removed)","mail_notification_smtp":"mail.jonesmail.me","mail_notification_ssl_enabled":true,"mail_notification_username":"(removed)","max_active_downloads":200,"max_active_torrents":200,"max_active_uploads":200,"max_connec":500,"max_connec_per_torrent":100,"max_ratio":0,"max_ratio_act":0,"max_ratio_enabled":true,"max_uploads":-1,"max_uploads_per_torrent":-1,"pex":true,"preallocate_all":false,"proxy_auth_enabled":true,"proxy_ip":"184.75.220.106","proxy_password":"(removed)","proxy_peer_connections":true,"proxy_port":1080,"proxy_type":4,"proxy_username":"(removed)","queueing_enabled":true,"random_port":false,"save_path":"/mnt/Torrents/Completed/","scan_dirs":{},"schedule_from_hour":8,"schedule_from_min":0,"schedule_to_hour":20,"schedule_to_min":0,"scheduler_days":0,"scheduler_enabled":false,"ssl_cert":"","ssl_key":"","temp_path":"/mnt/Torrents/Downloading/","temp_path_enabled":true,"up_limit":250,"upnp":true,"use_https":false,"web_ui_password":"(removed)","web_ui_port":80,"web_ui_upnp":false,"web_ui_username":"(removed)"}
19-10-21 14:58:37.3|Trace|HttpClient|Req: [GET] http://localhost:80/query/torrents?label=tv-sonarr&category=tv-sonarr
19-10-21 14:58:37.3|Trace|ConfigService|Using default config value for 'proxyenabled' defaultValue:'False'
19-10-21 14:58:37.3|Trace|HttpClient|Res: [GET] http://localhost:80/query/torrents?label=tv-sonarr&category=tv-sonarr: 200.OK (5 ms)
19-10-21 14:58:37.3|Trace|HttpClient|Response content (522 bytes): [{"added_on":1571687734,"category":"tv-sonarr","completion_on":1571687821,"dlspeed":0,"eta":8640000,"f_l_piece_prio":false,"force_start":false,"hash":"35f410312849c9ce9fb7adc7eea173ad63bff27b","name":"Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg]","num_complete":154,"num_incomplete":15,"num_leechs":0,"num_seeds":0,"priority":0,"progress":1,"ratio":0.002296133216223024,"save_path":"/mnt/Torrents/Completed/","seq_dl":false,"size":771077350,"state":"pausedUP","super_seeding":false,"upspeed":0}]
19-10-21 14:58:37.3|Debug|TrackedDownloadService|Tracking 'qbittorrent:Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg]': ClientState=Completed SonarrStage=Imported Episode='Modern Family - S11E04 WEBDL-720p v1' OutputPath=/mnt/Torrents/Completed/Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg].
19-10-21 14:58:37.3|Trace|EventAggregator|Publishing DownloadCompletedEvent
19-10-21 14:58:37.3|Trace|EventAggregator|DownloadCompletedEvent -> DownloadEventHub
19-10-21 14:58:37.3|Debug|DownloadEventHub|[Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg]] Removing download from qbittorrent history
19-10-21 14:58:37.3|Trace|HttpClient|Req: [POST] http://localhost:80/command/deletePerm: hashes=35f410312849c9ce9fb7adc7eea173ad63bff27b
19-10-21 14:58:37.3|Trace|ConfigService|Using default config value for 'proxyenabled' defaultValue:'False'
19-10-21 14:58:37.3|Trace|HttpClient|Res: [POST] http://localhost:80/command/deletePerm: 200.OK (5 ms)

I have noticed in the logs that there are quite a few “Database is locked” error occurring while Sonarr is doing various things. The database is hosted on a local disk that is not under heavy use. Are these types of messages expected, or are they actual errors that could account for the weird download behavior?

Oct 21 15:47:21 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:39 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:49 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked

Example of where some of these are happening:

Oct 21 16:02:35 jonesmedia mono: [Debug] Rarbg: Downloading Feed https://torrentapi.org/pubapi_v2.php?mode=search&search_tvdb=95011&search_string=S11E04&ranked=0&category=18%3B41%3B49&limit=100&token=s5d9v8wokq&format=json_extended&app_id=Sonarr
Oct 21 16:02:35 jonesmedia mono: [Trace] RateLimitService: Rate Limit triggered, delaying ‘torrentapi.org’ for 2.766 sec
Oct 21 16:02:38 jonesmedia mono: [Trace] HttpClient: Req: [GET] https://torrentapi.org/pubapi_v2.php?mode=search&search_tvdb=95011&search_string=S11E04&ranked=0&category=18%3B41%3B49&limit=100&token=s5d9v8wokq&format=json_extended&app_id=Sonarr
Oct 21 16:02:38 jonesmedia mono: [Trace] ConfigService: Using default config value for ‘proxyenabled’ defaultValue:‘False’
Oct 21 16:02:38 jonesmedia mono: [Trace] HttpClient: Res: [GET] https://torrentapi.org/pubapi_v2.php?mode=search&search_tvdb=95011&search_string=S11E04&ranked=0&category=18%3B41%3B49&limit=100&token=s5d9v8wokq&format=json_extended&app_id=Sonarr: 200.OK (219 ms)
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin:
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin: SQLite error (5): database is locked
Oct 21 16:02:38 jonesmedia mono: [Trace] Owin:

I found a reply on Reddit a while back that stated Sonarr won’t import episodes that it has already imported before. This was the bulk of my testing - delete an episode and have Sonarr grab it again.

I believe this might be my “issue”, however what phrase or statement should I be looking for in the logs where Sonarr indicates it’s ignoring a download because it has the same hash as a torrent it has seen before?

They’re not a problem if they aren’t happening many times a second. They wouldn’t lead to issues importing.

Not explicitly that it’s ignoring because it’s imported, but:

19-10-21 14:58:37.3|Debug|TrackedDownloadService|Tracking 'qbittorrent:Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg]': ClientState=Completed SonarrStage=Imported Episode='Modern Family - S11E04 WEBDL-720p v1' OutputPath=/mnt/Torrents/Completed/Modern.Family.S11E04.Pool.Party.720p.AMZN.WEBRip.DDP5.1.x264-NTb[rarbg].

Specifically SonarrStage=Imported

This topic was automatically closed 60 days after the last reply. New replies are no longer allowed.