Sonarr will freeze and get stuck on disk scan refreshes

Justin_MacIver · May 13, 2015, 12:02pm

I have the exact same issue just randomly sticks while scanning. I would start a scan and leave for work then come back to it still on scanning. Windows 8.1 Sonarr version 2.0.0.3004
Here is today’s log where it stopped on Ncis but it has stopped on Andromeda, Marvel’s Daredevil, Agents of Shield and more in the last 2 weeks. No new system updates downloaded, just started hanging while scanning.

15-5-13 06:56:50.4|Error|XemService|Error updating scene numbering mappings for: [259393][Fast N’ Loud]

System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a receive. —> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. —> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
at System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)
— End of inner exception stack trace —
at System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)
at System.Net.PooledStream.Read(Byte[] buffer, Int32 offset, Int32 size)
at System.Net.Connection.SyncRead(HttpWebRequest request, Boolean userRetrievedStream, Boolean probeRead)
— End of inner exception stack trace —
at System.Net.HttpWebRequest.GetResponse()
at NzbDrone.Common.Http.HttpClient.Execute(HttpRequest request) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Common\Http\HttpClient.cs:line 94
at NzbDrone.Common.Http.HttpClient.Get[T](HttpRequest request) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Common\Http\HttpClient.cs:line 169
at NzbDrone.Core.DataAugmentation.Xem.XemProxy.GetSceneTvdbMappings(Int32 id) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\DataAugmentation\Xem\XemProxy.cs:line 67
at NzbDrone.Core.DataAugmentation.Xem.XemService.PerformUpdate(Series series) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\DataAugmentation\Xem\XemService.cs:line 39

System.Net.WebException: Unable to connect to the remote server —> System.Net.Sockets.SocketException: No connection could be made because the target machine actively refused it 127.0.0.1:9091
at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
at System.Net.ServicePoint.ConnectSocketInternal(Boolean connectFailure, Socket s4, Socket s6, Socket& socket, IPAddress& address, ConnectSocketState state, IAsyncResult asyncResult, Exception& exception)
— End of inner exception stack trace —
at System.Net.HttpWebRequest.GetResponse()
at RestSharp.Http.GetRawResponse(HttpWebRequest request)
at RestSharp.Http.GetResponse(HttpWebRequest request)

15-5-13 06:57:03.5|Warn|DownloadMonitoringService|Unable to retrieve queue and history items from utorrent

System.Net.WebException: Unable to connect to the remote server —> System.Net.Sockets.SocketException: No connection could be made because the target machine actively refused it 127.0.0.1:9091
at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
at System.Net.ServicePoint.ConnectSocketInternal(Boolean connectFailure, Socket s4, Socket s6, Socket& socket, IPAddress& address, ConnectSocketState state, IAsyncResult asyncResult, Exception& exception)
— End of inner exception stack trace —
at NzbDrone.Core.Rest.RestSharpExtensions.ValidateResponse(IRestResponse response, IRestClient restClient) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Rest\RestSharpExtensions.cs:line 0
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.GetAuthToken(IRestClient client) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 228
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.BuildClient(UTorrentSettings settings) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 254
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.ProcessRequest(IEnumerable`1 arguments, UTorrentSettings settings) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 174
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.GetTorrents(UTorrentSettings settings) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 71
at NzbDrone.Core.Download.Clients.UTorrent.UTorrent.GetItems() in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrent.cs:line 72
at NzbDrone.Core.Download.TrackedDownloads.DownloadMonitoringService.ProcessClientDownloads(IDownloadClient downloadClient) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\TrackedDownloads\DownloadMonitoringService.cs:line 68

System.Net.WebException: Unable to connect to the remote server —> System.Net.Sockets.SocketException: No connection could be made because the target machine actively refused it 127.0.0.1:9091
at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
at System.Net.ServicePoint.ConnectSocketInternal(Boolean connectFailure, Socket s4, Socket s6, Socket& socket, IPAddress& address, ConnectSocketState state, IAsyncResult asyncResult, Exception& exception)
— End of inner exception stack trace —
at System.Net.HttpWebRequest.GetResponse()
at RestSharp.Http.GetRawResponse(HttpWebRequest request)
at RestSharp.Http.GetResponse(HttpWebRequest request)

15-5-13 06:57:13.6|Warn|DownloadMonitoringService|Unable to retrieve queue and history items from utorrent

System.Net.WebException: Unable to connect to the remote server —> System.Net.Sockets.SocketException: No connection could be made because the target machine actively refused it 127.0.0.1:9091
at System.Net.Sockets.Socket.DoConnect(EndPoint endPointSnapshot, SocketAddress socketAddress)
at System.Net.ServicePoint.ConnectSocketInternal(Boolean connectFailure, Socket s4, Socket s6, Socket& socket, IPAddress& address, ConnectSocketState state, IAsyncResult asyncResult, Exception& exception)
— End of inner exception stack trace —
at NzbDrone.Core.Rest.RestSharpExtensions.ValidateResponse(IRestResponse response, IRestClient restClient) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Rest\RestSharpExtensions.cs:line 0
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.GetAuthToken(IRestClient client) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 228
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.BuildClient(UTorrentSettings settings) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 254
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.ProcessRequest(IEnumerable`1 arguments, UTorrentSettings settings) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 174
at NzbDrone.Core.Download.Clients.UTorrent.UTorrentProxy.GetTorrents(UTorrentSettings settings) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrentProxy.cs:line 71
at NzbDrone.Core.Download.Clients.UTorrent.UTorrent.GetItems() in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\Clients\uTorrent\UTorrent.cs:line 72
at NzbDrone.Core.Download.TrackedDownloads.DownloadMonitoringService.ProcessClientDownloads(IDownloadClient downloadClient) in m:\BuildAgent\work\6c3239faf2b92630\src\NzbDrone.Core\Download\TrackedDownloads\DownloadMonitoringService.cs:line 68

markus101 · May 13, 2015, 2:32pm

Your logs are full errors about being unable to connect to uTorrent, but nothing else.

Please use hastebinnor a similar service to post logs, much more readable and the thread doesn’t become a pain to scroll though.

Trace logs of the disk rescan hanging are what we need to see, but please fix that error first.

Justin_MacIver · May 13, 2015, 3:30pm

ok now this time it has hung on Eureka. It [refreshseries]scanning disk for Eureka it has been on this for about 35 minutes then I closed Sonarr and restarted with log trace. It again froze with Eureka but normally is just random it is rare to see it twice in a row. http://pastebin.com/bkhCzDq9 is the trace log. Started at 10:15am ended about 10:27:am roughly.

markus101 · May 13, 2015, 3:43pm

Thanks, I believe this issue is already fix in develop, basically a scheduled task would run into a DB lock (non-critical), then it would try to log the error and end up running into another DB lock, again non-critical and this cycle would continue until there were too many locks to deal with and Sonarr hangs.

Justin_MacIver · May 13, 2015, 3:46pm

Good to know it is an issue in development this site has helped me through most of my minor problems by reading the forum. Until a fix or update I just will try to avoid a full scan for awhile. Thank you!

markus101 · May 13, 2015, 3:52pm

A full scan is going to run every 12 hours, nothing you can do to avoid that. We are looking for confirmation that we have fixed the issue though, so if you’re able to test the [develop] (https://github.com/Sonarr/Sonarr/wiki/Release-Branches) branch out, that would be great, you can always re-install the latest master release and restore the database backup from before the update if you run into issues.

Justin_MacIver · May 13, 2015, 6:49pm

Sure will install it right after work and let you know what happens!

Justin_MacIver · May 14, 2015, 1:53am

Installed the develop build and it still does the same thing. I notice in my process viewer that when it hangs it goes to 30-40% of my cpu after I terminate the program it sometimes completes the scan or hangs again a few times. Nothing too serious just annoying. The trace logs are nearly identical so didnt bother to post it.

markus101 · May 14, 2015, 3:30am

Are you seeing the SQLite error (5): database is locked messages as well?

Justin_MacIver · May 14, 2015, 5:09am

Yes seems to throw the error even sooner now but could just be my tired eyes. http://pastebin.com/ueThMh0c

cooler0918 · May 14, 2015, 4:19pm

Here is my about information

Version
2.0.0.3121
Mono Version
3.10.0 (tarball Mon Jan 26 02:53:37 UTC 2015)
AppData directory
/var/db/sonarr
Startup directory
/usr/pbi/sonarr-amd64/share/sonarr/NzbDrone

Running on FreeNAS-9.2.1.6-RELEASE-x64 (ddd1e39)

I restarted sonarr and it locked up again on another show. Log below:

TaskExtensions Task Error: database is locked database is locked 12:20pm
TaskExtensions Task Error: database is locked database is locked 12:19pm
TaskExtensions Task Error: database is locked database is locked 12:18pm
EventAggregator MetadataFileService failed while processing [MetadataFilesUpdated]: database is locked database is locked 12:16pm
RefreshEpisodeService Starting episode info refresh for: [289885][Gunslinger Stratos: The Animation] 12:16pm

markus101 · May 14, 2015, 8:40pm

You do get a few, but nothing that indicates that the DB locking is causing the hanging (only one at the end).

@cooler0918 Please pastebin trace log files showing the hanging.

cooler0918 · May 14, 2015, 10:31pm

http://pastebin.com/xZd2xBf1

Upgraded to:

Version
2.0.0.3133
Mono Version
3.10.0 (tarball Mon Jan 26 02:53:37 UTC 2015)
AppData directory
/var/db/sonarr
Startup directory
/usr/pbi/sonarr-amd64/share/sonarr/NzbDrone

The log is after upgrade.

cooler0918 · May 14, 2015, 10:33pm

How do I install libgdiplus? The sonarr install was off a pbi.

cooler0918 · May 14, 2015, 10:58pm

I restarted Jail and service, cleared logs and launch a disk refresh. Half way it blew up hard. Total app crash. Logs below:

https://app.box.com/s/5ivava263yti4gq2fnwphuqpedl1cm02

markus101 · May 14, 2015, 11:10pm

Which OS are you running Sonarr on? Ubuntu and Debian have issues with some recent kernels: Native mono crashes [kernel fix released], but other OSes may also be affected.

cooler0918 · May 15, 2015, 2:06am

The jail is running an instance of FeeBSD

markus101 · May 15, 2015, 3:57am

Doh. Are you able to capture the output from the mono command that launches Sonarr? Looking to see if you see a similar output to the errors in the thread I linked, because it sounds very similar.

Justin_MacIver · May 30, 2015, 8:55am

Spent the last 2 weeks on the dev build and it still has the same issue so uninstalled completely and went back to the normal release. It seems to only freeze on NCIS now guess I just will have to live with it for awhile. Not a problem just wanted you informed.

system · December 5, 2015, 10:03am