Emby unresponsive after upgrade to 3.0.5871.0

[theirman 0]

Hi,

 

When I noticed 5870 was released, I installed that one (was running the previous 5868 version).

I'd read about the database upgrade process on the blog, so I gave it over 10 minutes to do its thing after the initial start.

After the CPU usage had gone down to mere <3% I browsed to the settings page.

I noticed there was another new update (5871), so I upgraded to that one as well.

 

First thing I noticed when logging on (could have been the same in 70, didn't care to check), is that the collections icon had changed to a default 'play' image.

Second thing is that the (home) page loaded extremely slowly, and the thumbnails for the series came through pretty slowly.

If I click on a series (or any place with a lot of thumbnails) the images come in slowly, and some don't show up at all.

 

If I open up the episode where the thumbnail is missing the file itself also refuses to play.

If I go to an episode with thumbnail, they also refuse to play. Same with any movie.

In fact, after clicking to 'play' any media, just a waiting circle appears, and it never goes away, even when navigating to another page.

If you click "server options" the overview page looks all weird.

 

I'm forced to close the tab, and open a new one.

Looking for another file to play will display the circle again, but this time the details page will be blank and you're unable to do anything:

Even the sidebar looks odd.

 

When you close this tab, and try to browse to Emby again, the page will just keep on loading ("waiting for available socket").

And then I'm forced to stop the server...

 

Theme music does stil play automatically when navigating to a series where it's available.

If I restart the server I will be able to play an episode or 2. Or a movie. But the moment I had one, and try another it'll time out and get unresponsive again. Forcing a restart.

 

I've tried Chrome, Edge, and the iOS app.

 

Nothing particular in the logfiles except for the occasional socketexception:

        *** Error Report ***
        Version: 3.0.5871.0
        Command line: /usr/local/lib/emby-server/MediaBrowser.Server.Mono.exe -ffmpeg /usr/local/bin/ffmpeg -ffprobe /usr/local/bin/ffprobe -programdata /var/db/emby-server
        Operating system: Unix 9.1.0.0
        Processor count: 8
        64-Bit OS: True
        64-Bit Process: True
        Program data path: /var/db/emby-server
        Mono: 4.2.2 (Stable 4.2.2.10/7b87787 Sat Jan 16 12:57:01 UTC 2016)
        Application Path: /usr/local/lib/emby-server/MediaBrowser.Server.Mono.exe
        Write failure
        System.IO.IOException
          at System.Net.Sockets.NetworkStream.Write (System.Byte[] buffer, Int32 offset, Int32 size) <0x815b8ede0 + 0x0013c> in <filename unknown>:0
          at SocketHttpListener.Net.ResponseStream.InternalWrite (System.Byte[] buffer, Int32 offset, Int32 count) <0x815b8ed30 + 0x0009a> in <filename unknown>:0
          at SocketHttpListener.Net.ResponseStream.Write (System.Byte[] buffer, Int32 offset, Int32 count) <0x815b8c770 + 0x001f5> in <filename unknown>:0
          at System.IO.Stream.InternalCopyTo (System.IO.Stream destination, Int32 bufferSize) <0x81aba20b0 + 0x00053> in <filename unknown>:0
          at System.IO.Stream.CopyTo (System.IO.Stream destination, Int32 bufferSize) <0x81aba1e30 + 0x000b5> in <filename unknown>:0
          at (wrapper remoting-invoke-with-check) System.IO.Stream:CopyTo (System.IO.Stream,int)
          at MediaBrowser.Server.Implementations.HttpServer.StreamWriter.WriteToInternal (System.IO.Stream responseStream) <0x81aba1b70 + 0x0005a> in <filename unknown>:0
        InnerException: System.Net.Sockets.SocketException
        The socket has been shut down
          at System.Net.Sockets.Socket.Send (System.Byte[] buffer, Int32 offset, Int32 size, SocketFlags flags) <0x815b8f050 + 0x00103> in <filename unknown>:0
          at System.Net.Sockets.NetworkStream.Write (System.Byte[] buffer, Int32 offset, Int32 size) <0x815b8ede0 + 0x000ad> in <filename unknown>:0

... which I hadn't seen before either.

But it didn't appear in the attached one.

 

Basically, what did the upgrade to 5670/1 do, and how can I get everything back working?

Is there any way to make the logfiles more verbose, because they don't seem to hold any value in this case (as far as I can see).

server-63591591935.txt

server-63591594436.txt

[Luke 39686]

Hi, your logs look fine for the  most part. I do see one issue I can fix related to displaying tv series, but shouldn't cause every screen to perform slowly. I would suggest removing plugins and seeing if the issue persists with just the core server. thanks.

[theirman 0]

Hi Luke,

 

I've recompiled Mono because I remember that updated as well, and I just wanted to make sure it wasn't just emby. (No difference I'm afraid.)

The same thing still happens in Chrome, but... I've noticed that the emby app performs well.

I can select series, click and play, everything seems to work smoothly. I did seem to have lost the ability to display subtitles however. The subtitle is selected, but it just won't show.

Minor problem of course, compared to when I use the web site in Chrome.

 

In the attached logfile the following thing happened:

1. Cleanly started emby-server

2. Browsed to a movie (Spectre) on the iOS app (iPad)

3. Played the movie for some time (as said before; no subtitles - which is also new, never had problems with that before now)

4. Stopped playing (saved the progress)

5. Exited app

6. Surfed to the emby website in Chrome

7. Navigated to Spectre, clicked Play > Resume

8. Preparation screen opens where it usually starts transcoding the movie; nothing happens

9. After waiting a few minutes (with nothing happening - no transcode log got created) closed the player (X)

10. Landed back on the initial movie page, but there is no scrollbar and Chrome keeps "loading" the page with the message "Waiting for ..."

11. Closed tab

12. Opened Emby iOS app on iPad; page loads fine > exited app

13. Opened Emby iOS app on iPhone; page loads fine

14. Clicked the Spectre "In progress" thumbnail; click play > resume

15. Nothing happens ; switch to 480p - 1.0Mbps playback profile

16. Still nothing (there is however a new transcode file - see attachment)

17. Close app ; re-open app ; all dead (keeps loading)

18. Eventually times out and gives the default "Connect to Emby server" page

19. Open iPad iOS app ; same here ; spinning until time-out

20. Browse again to the website in Chrome ; never loads

21. Shut down server

 

I've attached the log and the 2 transcode files (the first successful one (iPad) ; the bad one (iPhone)).

Chrome didn't produce an transcode logfile, but I do see a lot of these errors for that moment:

2016-02-20 22:16:45.8100 Info HttpServer: HTTP GET http://app.emby.medi:8080/emby/Plugins/SecurityInfo. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/48.0.2564.109 Safari/537.36
2016-02-20 22:16:45.8132 Info HttpServer: HTTP Response 200 to 192.168.3.100. Time: 3ms. http://app.emby.medi:8080/emby/Plugins/SecurityInfo
2016-02-20 22:16:45.8215 Info HttpServer: HTTP GET http://app.emby.medi:8080/emby/Users/9f67fb6428434f898e222991878441a4/Items/6bec2d2beb5b65667d5a24160cad3049. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/48.0.2564.109 Safari/537.36
2016-02-20 22:16:46.0007 Info HttpServer: HTTP Response 200 to 192.168.3.100. Time: 179ms. http://app.emby.medi:8080/emby/Users/9f67fb6428434f898e222991878441a4/Items/6bec2d2beb5b65667d5a24160cad3049
2016-02-20 22:16:46.0249 Info HttpServer: HTTP GET http://app.emby.medi:8080/emby/Playback/BitrateTest?Size=1000000. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/48.0.2564.109 Safari/537.36
2016-02-20 22:16:46.0427 Info HttpServer: HTTP GET http://app.emby.medi:8080/emby/Playback/BitrateTest?Size=2400000. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/48.0.2564.109 Safari/537.36
2016-02-20 22:16:46.0873 Info HttpServer: HTTP POST http://app.emby.medi:8080/emby/Items/6bec2d2beb5b65667d5a24160cad3049/PlaybackInfo?UserId=9f67fb6428434f898e222991878441a4&StartTimeTicks=3451866999. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/48.0.2564.109 Safari/537.36
2016-02-20 22:16:46.0923 Info App: Profile: Unknown Profile, Path: nfs://192.168.3.5//mnt/HDD/Video/Films/Spectre (2015)/Spectre (2015).mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2016-02-20 22:16:46.0923 Info App: Profile: Unknown Profile, No direct play profiles found for Path: nfs://192.168.3.5//mnt/HDD/Video/Films/Spectre (2015)/Spectre (2015).mp4
2016-02-20 22:16:46.0924 Info App: Profile: Unknown Profile, Path: nfs://192.168.3.5//mnt/HDD/Video/Films/Spectre (2015)/Spectre (2015).mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2016-02-20 22:16:46.0924 Info App: Profile: Unknown Profile, No direct play profiles found for Path: nfs://192.168.3.5//mnt/HDD/Video/Films/Spectre (2015)/Spectre (2015).mp4
2016-02-20 22:16:46.0924 Info App: Profile: Unknown Profile, Path: nfs://192.168.3.5//mnt/HDD/Video/Films/Spectre (2015)/Spectre (2015).mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2016-02-20 22:16:46.0924 Info App: Profile: Unknown Profile, No direct play profiles found for Path: nfs://192.168.3.5//mnt/HDD/Video/Films/Spectre (2015)/Spectre (2015).mp4
2016-02-20 22:16:46.0942 Info HttpServer: HTTP Response 200 to 192.168.3.100. Time: 7ms. http://app.emby.medi:8080/emby/Items/6bec2d2beb5b65667d5a24160cad3049/PlaybackInfo?UserId=9f67fb6428434f898e222991878441a4&StartTimeTicks=3451866999

Interestingly the logfile never registered the server shutting down; it just ended minutes earlier.

Seems like it just gave up when I asked to resume the movie on my iPhone.

 

Does this help in any way?

I'll try without the plugins now. And without ever going there using Chrome (just switch between iPhone and iPad - check if that works).

 

Thanks!

server-63591603640.txt

transcode-iPad.txt

transcode-iPhone.txt

[theirman 0]

Just spent more than 15 minutes playing the movie juggling the playback between iPhone and iPad.

Even browsing my Emby library on the iPad while Spectre was playing on the iPhone ; everything went smooth as can be.

Just missing the subtitles 

 

So it seems to me that my desktop is somehow the culprit?

After closing all the iOS apps I swithed to Edge and browsed to the Emby website.

Slow (or not) loading images, spinning circle, clicking on the Spectre movie just gave me a blank "Details" page with the spinning circle of death.

And now it appears the server gave up again, site and apps all time out again trying to connect to it (and the logfile also just stopped recording things ; didn't log the shut down again).

 

I just looked at the process list and it appears that ffmpeg had been transcoding since 23:47, about the time the iPhone iOS app stopped responding in the previous post. So that process never got terminated by emby (same as the log ; probably just "gave up" for some reason at that point).

 

So if I just keep using the iOS apps and don't mind not having subtitles, I can just use emby as before.

Tomorrow I'll try removing plugins (but I have a hard time thinking this has anything to do with plugins).

Current plugins:

ps-output.txt

server-63591606038.txt

[Luke 39686]

Thanks for the investigation. We really need to know the results without plugins before any assessment can be made.

[theirman 0]

Well, I've been testing throughout the day.

 

First thing today I just started browsing my library on the iOS app until that made the server irresponsive as well.

After restarting the server I changed some settings in the coverart plugin to force it to remake most thumbnails. Result was that the website became completely inoperable and I had to take a shortcut to reach the dashboard.

Removed the coverart plugin & kodi sync queue. Made everything a lot more stable. Browsing was much snappier (doesn't look as good now though).

After a while it also crawled to a halt, so I removed every single plugin. Nothing yet since.

 

So I'll just keep all plugins uninstalled for a whole week and see if it comes back.

 

The playback is fixed on the website, but the iOS app keeps refusing to display subtitles (only issue now). I've visited the website on mobile safari, and the subtitle there shows fine.

Guess I'll just keep using mobile safari until that's fixed.

 

I've attached the server log if you want a look (sitesub-appnosub.txt).

2016-02-21 21:14:13.8305 -> 2016-02-21 21:16:08.9585 : mobile safari (direct IP) - subs
2016-02-21 21:16:36.0007 -> 2016-02-21 21:18:42.0519 : iOS app (remote URL) - no sub
2016-02-21 21:23:14.9612 -> 2016-02-21 21:25:09.8066 : mobile safari (remote URL) - subs

Same device. Didn't matter if I used direct IP or URL. Safari shows subtitles, app doesn't.

 

So my plan now is to temporarily abandon the iOS app, and keep all plugins deinstalled.

I'll enable one plugin every week until I find the culprit...

sitesub-appnosub.txt

[Luke 39686]

thanks. i am looking into the ios subtitle issue. it is resolved for safari on the dev and stable branches, but not the app yet.