No Streaming after Server Upgrade

[jj12345 25]

Upgraded server to 3.0.5233.40306 and now none of my Roku devices will stream any media browser content using production channel, beta channel, or RokuMB++ channel.  Login fine, browse around fine, pick movie or tv show to watch (iso, mp4, etc. doesn't seem to matter) it says retrieving gets to what looks like around 15% stalls and then times out and goes back to the media description and play menu.

 

Streams fine to web browser and MB Theater... just the Roku devices have lost streaming.  Rebooted Roku devices, scanned for updates, other non-MB related channels stream without a problem.

 

Any suggestions?

[Luke 36997]

Can you post server logs? Thanks.

 

http://mediabrowser.tv/community/index.php?/topic/790-how-to-report-a-problem/

[jj12345 25]

I disabled both.  Enabled play to... restarted service... test worked.  Enabled server... restarted service... test worked.  Enabled server blast alive messages... restarted service... test worked.  So everything is the way it was before and it works fine.

 

Hmmmm.... guess it was the act of stopping and restarting the MB service that did the trick.  Sorry I hadn't thought of that before starting the thread... it had recently been stopped and restarted because of the upgrade so it didn't occur to me to consider that.

[Luke 36997]

well you shouldn't need to restart the server to play video so i'm not so sure about that. But thanks for testing.

[jj12345 25]

Log file is attached.  Looks like I have a lot of DLNA noise in there... AFAIK I'm not using any DLNA clients just native MB clients (Roku, Android, Windows 8.1, Windows Phone, iOS, MBT, Web) so I may just turn of DLNA but I don't think that's the problem.

 

The logs don't indicate the content or device requesting content so I'm not sure what to pinpoint.  I can try turning off DLNA, clearing the log, and trying again if there is too much noise in there.  Let me know.  You can also private message me if you want remote access.

 

 

server-63534585600.zip

[jj12345 25]

I don't disagree... and it still played videos from MBT and web client so it felt like an issue somehow related to the Roku client.  I guess chalk it up to one of the joys of computer programming.

[jj12345 25]

I looked in an old log from before the most recent server upgrade and it was not full of DLNA errors like the current log.

 

I turned off DNLA play to and DNLA server since I don't use DNLA and restarted the service to get a new log file.  

 

Now the streaming on the Roku works fine.

[Luke 36997]

which dlna feature did you disable? if you disabled both the server and play to, can you try one at a time just for testing purposes?

[speechles 1917]

It's the DAMN dlna server option causing it... Had this happen to me. It is NOT related to dlna play-to

 

FYI, here is the important part of the debug.

 

server log

2014-05-03 01:33:07.3212 Debug - HttpServer: HTTP GET http://192.168.1.67:8096/mediabrowser/Videos/9a2a785875f2be57697a1983f1dc6beb/stream.m3u8?profile=high&deviceid=1GJ37M000975&audiochannels=2&audiobitrate=128000&maxwidth=1920&timestampoffsetms=0&audiocodec=aac&videobitrate=20000000&level=4.0&maxheight=1080&videocodec=h264&StartTimeTicks=21000000000
2014-05-03 01:33:07.3632 Debug - Dlna: No matching device profile found. The default will be used. User-agent: Roku/DVP-5.4 (045.04E03340A). 
2014-05-03 01:33:07.6182 Info - App: C:\Users\speechles\AppData\Roaming\MediaBrowser-Server\ffmpeg\20140304\ffmpeg.exe -ss 2100 -fflags genpts -i file:"F:\-- Movies\Sleepaway Camp (1983)\Sleepaway.Camp.[1983].DVDRip.XviD-BLiTZKRiEG.avi" -map_metadata -1 -threads 2 -map 0:0 -map 0:1 -map -0:s -codec:v:0 libx264 -preset superfast -crf 20 -maxrate 1160325 -bufsize 2320650 -r 23.97602 -vsync vfr -profile:v high -level 4.0 -force_key_frames expr:if(isnan(prev_forced_t),gte(t,.1),gte(t,prev_forced_t+5)) -vf "scale=trunc(min(iw\,1920)/2)*2:trunc(min((iw/dar)\,1080)/2)*2" -sc_threshold 0 -codec:a:0 aac -strict experimental -ac 2 -ab 103592 -af "adelay=1,aresample=async=1" -hls_time 10 -start_number 0 -hls_list_size 1440 "C:\Users\speechles\AppData\Roaming\MediaBrowser-Server\transcoding-temp\1d1821f9-4993-7ba6-d8cd-4eaff52def20.m3u8"
2014-05-03 01:33:11.2452 Info - App: Updating series from tvdb 73762, language en
2014-05-03 01:33:11.2452 Info - App: HttpClientManager GET: http://www.thetvdb.com/api/B89CE93890E9419B/series/73762/all/en.zip
2014-05-03 01:33:19.8682 Info - App: Updating series from tvdb 73787, language en
2014-05-03 01:33:19.8682 Info - App: HttpClientManager GET: http://www.thetvdb.com/api/B89CE93890E9419B/series/73787/all/en.zip
2014-05-03 01:33:22.3902 Info - App: Updating series from tvdb 73871, language en
2014-05-03 01:33:22.3902 Info - App: HttpClientManager GET: http://www.thetvdb.com/api/B89CE93890E9419B/series/73871/all/en.zip
2014-05-03 01:33:23.8942 Debug - HttpServer: HTTP DELETE http://192.168.1.67:8096/mediabrowser/Users/14da26f23a8692342b18ad401d2642ab/PlayingItems/9a2a785875f2be57697a1983f1dc6beb?PositionTicks=21000000000
2014-05-03 01:33:23.8942 Debug - HttpServer: HTTP Response 204 to 192.168.1.239:36639. Response time: 3 ms
	Url: http://192.168.1.67:8096/mediabrowser/Users/14da26f23a8692342b18ad401d2642ab/PlayingItems/9a2a785875f2be57697a1983f1dc6beb?PositionTicks=21000000000
2014-05-03 01:33:23.8982 Debug - HttpServer: HTTP DELETE http://192.168.1.67:8096/mediabrowser/Videos/ActiveEncodings?DeviceId=1GJ37M000975
2014-05-03 01:33:23.9382 Info - App: Killing ffmpeg process for C:\Users\speechles\AppData\Roaming\MediaBrowser-Server\transcoding-temp\1d1821f9-4993-7ba6-d8cd-4eaff52def20.m3u8
2014-05-03 01:33:24.0092 Info - App: FFMpeg exited with an error.
2014-05-03 01:33:24.0092 Info - App: Deleting partial stream file(s) C:\Users\speechles\AppData\Roaming\MediaBrowser-Server\transcoding-temp\1d1821f9-4993-7ba6-d8cd-4eaff52def20.m3u8

ffmpeg logs

  built on Mar  4 2014 00:44:56 with gcc 4.8.2 (GCC)
  configuration: --enable-gpl --enable-version3 --disable-w32threads --enable-avisynth --enable-bzlib --enable-fontconfig --enable-frei0r --enable-gnutls --enable-iconv --enable-libass --enable-libbluray --enable-libcaca --enable-libfreetype --enable-libgsm --enable-libilbc --enable-libmodplug --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-librtmp --enable-libschroedinger --enable-libsoxr --enable-libspeex --enable-libtheora --enable-libtwolame --enable-libvidstab --enable-libvo-aacenc --enable-libvo-amrwbenc --enable-libvorbis --enable-libvpx --enable-libwavpack --enable-libx264 --enable-libx265 --enable-libxavs --enable-libxvid --enable-zlib
  libavutil      52. 66.101 / 52. 66.101
  libavcodec     55. 52.102 / 55. 52.102
  libavformat    55. 33.101 / 55. 33.101
  libavdevice    55. 11.100 / 55. 11.100
  libavfilter     4.  2.100 /  4.  2.100
  libswscale      2.  5.101 /  2.  5.101
  libswresample   0. 18.100 /  0. 18.100
  libpostproc    52.  3.100 / 52.  3.100
Input #0, avi, from 'file:F:\-- Movies\Sleepaway Camp (1983)\Sleepaway.Camp.[1983].DVDRip.XviD-BLiTZKRiEG.avi':
  Metadata:
    encoder         : VirtualDubMod 1.5.4.1 (build 2178/release)
    IAS1            : English
  Duration: 01:24:22.15, start: 0.000000, bitrate: 1160 kb/s
    Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 704x384 [SAR 1:1 DAR 11:6], 23.98 tbr, 23.98 tbn, 23.98 tbc
    Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16p, 103 kb/s
[libx264 @ 04bd0060] using SAR=1/1
[libx264 @ 04bd0060] using cpu capabilities: MMX2 SSE2Slow SlowCTZ
[libx264 @ 04bd0060] profile High, level 4.0
Output #0, hls, to 'C:\Users\speechles\AppData\Roaming\MediaBrowser-Server\transcoding-temp\1d1821f9-4993-7ba6-d8cd-4eaff52def20.m3u8':
  Metadata:
    encoder         : Lavf55.33.101
    Stream #0:0: Video: h264 (libx264), yuv420p, 704x384 [SAR 1:1 DAR 11:6], q=-1--1, max. 1160 kb/s, 90k tbn, 23.98 tbc
    Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp, 103 kb/s
Stream mapping:
  Stream #0:0 -> #0:0 (mpeg4 -> libx264)
  Stream #0:1 -> #0:1 (mp3 -> aac)
Press [q] to stop, [?] for help
frame=    0 fps=0.0 q=0.0 size=N/A time=00:00:00.36 bitrate=N/A    
[adts @ 06234120] Encoder did not produce proper pts, making some up.
frame=   12 fps= 12 q=17.0 size=N/A time=00:00:01.04 bitrate=N/A    
frame=   52 fps= 34 q=21.0 size=N/A time=00:00:02.70 bitrate=N/A    
frame=   83 fps= 41 q=21.0 size=N/A time=00:00:04.01 bitrate=N/A    
frame=  115 fps= 46 q=20.0 size=N/A time=00:00:05.33 bitrate=N/A    
frame=  149 fps= 49 q=18.0 size=N/A time=00:00:06.76 bitrate=N/A    
frame=  182 fps= 51 q=20.0 size=N/A time=00:00:08.12 bitrate=N/A    
frame=  205 fps= 51 q=18.0 size=N/A time=00:00:09.08 bitrate=N/A    
frame=  235 fps= 52 q=18.0 size=N/A time=00:00:10.32 bitrate=N/A    
frame=  239 fps= 47 q=17.0 size=N/A time=00:00:10.51 bitrate=N/A    
frame=  270 fps= 48 q=20.0 size=N/A time=00:00:11.79 bitrate=N/A    
frame=  301 fps= 49 q=19.0 size=N/A time=00:00:13.07 bitrate=N/A    
frame=  325 fps= 49 q=19.0 size=N/A time=00:00:14.08 bitrate=N/A    
frame=  354 fps= 50 q=20.0 size=N/A time=00:00:15.33 bitrate=N/A    
frame=  398 fps= 52 q=19.0 size=N/A time=00:00:17.13 bitrate=N/A    
frame=  430 fps= 53 q=22.0 size=N/A time=00:00:18.47 bitrate=N/A    

Keep in mind I could direct stream just fine, it was only when transcoding it seems to default to dlna serving to the roku and this causes a delay which leads to a timeout. At least this is what appears to be happening to me. But to any others suffering this issue with AVI or similar videos not playing try disable server dlna and restart mediabrowser3 and it starts to play normally again like it used to. Hopefully with this info this can get resolved.

[jj12345 25]

Ok... I'm still having problems.  I now have DLNA server and DLNA play-to both disabled.  Experiencing the exact same problem I opened this thread with.  I don't think DLNA is the problem.  Stopping and restarting the Media Browser windows service fixes the problem.  Maybe I need to automatically restart this every night?

 

In the attached log the Roku viewing attempt is at 2014-05-03 15:01:27.1464

 

Then I was told it wasn't working and stopped and restarted the service at 2014-05-03 15:10:10.5012

 

Then it worked fine on the Roku.

server-63534672000.txt

[speechles 1917]

You are right yo. It happens when the roku goes to sleep and starts bouncing the "roku" logo all about the screen as if it were a pong ball. After this happens, you wake back up the roku and mediabrowser3 is where it left off. After this transcoded videos won't play for me. You are correct that a full restart of the server solves the problem, somehow?!?!? I have DLNA off for both server and play-to (last night I changed this) and this morning only direct-play works, anything transcoded never starts (again, DAMNIT!), A small part of the loading bar loads up with blue (approx 15% as OP stated) and it never moves from there. It just bails back to the details screen for that episode after waiting for timeout. So perhaps my race to blame wasn't completely accurate. This should be easy for anyone to replicate. Logs shouldn't be entirely needed. I am also using same Mediabrowser3 server version as OP.

Edited May 3, 2014 by speechles

[jj12345 25]

Upgraded to 3.0.5238.39498 with no difference.  Release notes didn't give me hope that it would be fixed but figured I'd report here anyway.  Also tried running tray app instead of windows service... again no difference.  I have to stop and restart media server several times a day or it will just not stream to the Roku device.  I don't know if it's related to the screen saver as @@speechles observed or if it's just after a certain amount of time active or inactive that it happens.  But Roku behaves as reported... retrieving media to about 15% and then just stalls and times out back to the selected media details screen.  Only solution I've seen is to stop and restart media server and then it works 100% of the time right after that until it stops working again.

 

I'm guessing this must be an edge case because there's only 2 of us complaining about it.

[hoxtonia 46]

I too am experiencing this....

 

 

Sent from my iPhone using Tapatalk

[hoxtonia 46]

Sorry Luke that was rather vague...

 

So I have 3 NowTV boxes located around the country with various family members connected to my server....

 

These have been working great with no complaints for several months - then a few days ago I started getting texts telling me they couldn't play anything - NowTV box loads everything they select something to play - retrieves media to about 15% stalls and kicks out to media details screen - I had a look at logs - full of DLNA errors - disabled both play to and server yesterday and now waiting for feedback as to whether this has sorted the issue...I will update once I know.....

 

 

Sent from my iPhone using Tapatalk

[hoxtonia 46]

Just doing school run :-) but will post logs etc once I can get someone to test playback on the remote NowTV boxes

 

 

Sent from my iPhone using Tapatalk

[radeon 677]

Bit of a random one here, but try this...

 

Make sure your "C:\Users\{YourUsername}\AppData\Roaming\MediaBrowser-Server\transcoding-temp" folder is empty and try again.

 

A couple of days ago everything stopped for me just like it did for you @@hoxtonia, nothing seemed to be indicated in the logs so I just started picking away at things and that one fixed it for me.

[radeon 677]

Yea, one of the people accessing remotely got into a problem with their connection mid stream which seemed to leave some files hanging around in the folder. Ill be intrigued to see if it resolves it for the others...

[Luke 36997]

Yea, one of the people accessing remotely got into a problem with their connection mid stream which seemed to leave some files hanging around in the folder. Ill be intrigued to see if it resolves it for the others...

 

If that solves it then well done. I will need to see why those files aren't getting cleaned up immediately when playback stops (that is what's supposed to happen).

[hoxtonia 46]

You are the man! I will keep a close eye on whether the files get cleared out automatically - hoping this was just a temporary glitch - my mum (nearly 80) has really been enjoying my 30tb collection - it does need to just work for her though. Will get her to confirm all back in working order on her NowTV box tomorrow

 

 

Sent from my iPhone using Tapatalk

[Luke 36997]

I too am experiencing this....

 

 

Sent from my iPhone using Tapatalk

 

Can you describe it and provide more information?

 

http://mediabrowser.tv/community/index.php?/topic/790-how-to-report-a-problem/

[hoxtonia 46]

Thanks radeon - will try this when I eventually get home....school run done - now waiting to pick up from Guides!

 

 

Sent from my iPhone using Tapatalk

[Luke 36997]

Ok, sorry about that. Just keep the information flowing because that's the best way to solve something like that, is to get as much data as possible to try and find patterns.

[hoxtonia 46]

So there were files lurking in the temp folder - I cleared them out and 10 minutes later get a text from my niece in Swindon telling me all working now - hadn't even asked them to retest - so big thanks - I have a happy extended family again!

 

 

Sent from my iPhone using Tapatalk

[radeon 677]

Wupaa! Check me out! hahaha Well at least you have a temp manual fix for now. :-D

[Luke 36997]

Thanks Radeon. That is actually one thing that I thought of. The temp folder is cleared at startup (and other times), so that might be why a server restart solves the issue.