Error playing channel on Roku in a sustained manner

December 28, 2017

Hello, I am using Emby server on windows and the Roku client. I also have an IPTV tuner configured.

Every so often, the stream starts, but then ends up in a loading state. After attempting to load for a little while, the stream closes out. Once the stream closes out, nothing on the IPTV works until I do a server restart. Here are some logs that I think are relevant. If you need complete logs, I am happy to provide, but I would like to do it via email since the logs are filled with my sub userid and password information.

TV playing file:

2017-12-28 08:29:14.475 Info HttpResultFactory: Setting range response values for C:\Users\media\AppData\Roaming\Emby-Server\programdata\transcoding-temp\467a38d9b6a66cefdb7366d5cf1cf4fe128.ts. RangeRequest: bytes=0-262143 Content-Length: 262144, Content-Range: bytes 0-262143/523768
2017-12-28 08:29:14.482 Info HttpServer: HTTP Response 206 to 192.168.1.100. Time: 11ms. http://192.168.1.183:8096/emby/videos/c348c96b44d46d6d3771591ca8cb95d9/hls/467a38d9b6a66cefdb7366d5cf1cf4fe/467a38d9b6a66cefdb7366d5cf1cf4fe128.ts?DeviceId=4dd32a15-4a13-5469-acf8-550835484fe8&MediaSourceId=e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5&VideoCodec=h264&AudioCodec=mp3,aac&AudioStreamIndex=-1&VideoBitrate=23787575&AudioBitrate=93023&MaxAudioChannels=6&MaxFramerate=60&MaxWidth=3840&MaxHeight=2160&MaxVideoBitDepth=8&PlaySessionId=6c171d28999d4eb6b62b4fc62183f283&LiveStreamId=a17c75760a04e99b68cf766e11316e1c_09efa0d56b934a82adec00a87b837fb0_e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5&CopyTimestamps=false&TranscodingMaxAudioChannels=2&EnableSubtitlesInManifest=false&RequireAvc=false&RequireNonAnamorphic=false&SegmentContainer=ts&SegmentLength=3&MinSegments=1&BreakOnNonKeyFrames=True&TranscodeReasons=ContainerNotSupported,VideoCodecNotSupported,AudioCodecNotSupported&h264-maxrefframes=16&h264-profile=high,main,baseline,constrainedbaseline&h264-level=51 
2017-12-28 08:29:14.492 Info HttpServer: HTTP GET http://192.168.1.183:8096/emby/videos/c348c96b44d46d6d3771591ca8cb95d9/hls/467a38d9b6a66cefdb7366d5cf1cf4fe/467a38d9b6a66cefdb7366d5cf1cf4fe128.ts?DeviceId=4dd32a15-4a13-5469-acf8-550835484fe8&MediaSourceId=e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5&VideoCodec=h264&AudioCodec=mp3,aac&AudioStreamIndex=-1&VideoBitrate=23787575&AudioBitrate=93023&MaxAudioChannels=6&MaxFramerate=60&MaxWidth=3840&MaxHeight=2160&MaxVideoBitDepth=8&PlaySessionId=6c171d28999d4eb6b62b4fc62183f283&LiveStreamId=a17c75760a04e99b68cf766e11316e1c_09efa0d56b934a82adec00a87b837fb0_e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5&CopyTimestamps=false&TranscodingMaxAudioChannels=2&EnableSubtitlesInManifest=false&RequireAvc=false&RequireNonAnamorphic=false&SegmentContainer=ts&SegmentLength=3&MinSegments=1&BreakOnNonKeyFrames=True&TranscodeReasons=ContainerNotSupported,VideoCodecNotSupported,AudioCodecNotSupported&h264-maxrefframes=16&h264-profile=high,main,baseline,constrainedbaseline&h264-level=51. Host=192.168.1.183:8096, Range=bytes=262144-523767, User-Agent=Roku/DVP-8.1 (298.01E04042A), Accept=*/*
2017-12-28 08:29:14.495 Info HttpResultFactory: Setting range response values for C:\Users\media\AppData\Roaming\Emby-Server\programdata\transcoding-temp\467a38d9b6a66cefdb7366d5cf1cf4fe128.ts. RangeRequest: bytes=262144-523767 Content-Length: 261624, Content-Range: bytes 262144-523767/523768
2017-12-28 08:29:14.503 Info HttpServer: HTTP Response 206 to 192.168.1.100. Time: 11ms. http://192.168.1.183:8096/emby/videos/c348c96b44d46d6d3771591ca8cb95d9/hls/467a38d9b6a66cefdb7366d5cf1cf4fe/467a38d9b6a66cefdb7366d5cf1cf4fe128.ts?DeviceId=4dd32a15-4a13-5469-acf8-550835484fe8&MediaSourceId=e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5&VideoCodec=h264&AudioCodec=mp3,aac&AudioStreamIndex=-1&VideoBitrate=23787575&AudioBitrate=93023&MaxAudioChannels=6&MaxFramerate=60&MaxWidth=3840&MaxHeight=2160&MaxVideoBitDepth=8&PlaySessionId=6c171d28999d4eb6b62b4fc62183f283&LiveStreamId=a17c75760a04e99b68cf766e11316e1c_09efa0d56b934a82adec00a87b837fb0_e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5&CopyTimestamps=false&TranscodingMaxAudioChannels=2&EnableSubtitlesInManifest=false&RequireAvc=false&RequireNonAnamorphic=false&SegmentContainer=ts&SegmentLength=3&MinSegments=1&BreakOnNonKeyFrames=True&TranscodeReasons=ContainerNotSupported,VideoCodecNotSupported,AudioCodecNotSupported&h264-maxrefframes=16&h264-profile=high,main,baseline,constrainedbaseline&h264-level=51

And then, for some reason the stream stops, which you see here. The stop was not initiated by the user. I am not sure if that is what logs at 815 are eluding to or not.

2017-12-28 08:29:17.771 Info HttpServer: HTTP POST http://192.168.1.183:8096/emby/Sessions/Playing/Stopped. UserAgent: Roku/DVP-8.1 (298.01E04042A)
2017-12-28 08:29:17.805 Info App: Deleting partial stream file(s) C:\Users\media\AppData\Roaming\Emby-Server\programdata\transcoding-temp\467a38d9b6a66cefdb7366d5cf1cf4fe.m3u8
2017-12-28 08:29:17.815 Info SessionManager: Playback stopped reported by app Roku SG 3.0.54 playing USA/CA: HGTV HD. Stopped at 385000 ms
2017-12-28 08:29:17.822 Info MediaSourceManager: Closing live stream 09efa0d56b934a82adec00a87b837fb0_e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5 with provider LiveTvMediaSourceProvider
2017-12-28 08:29:17.825 Info App: Closing live stream from Emby, stream Id: e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5
2017-12-28 08:29:17.828 Info App: Live stream e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5 consumer count is now 0
2017-12-28 08:29:17.828 Info App: Closing live stream e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5
2017-12-28 08:29:17.828 Info App: Closing SharedHttpStream
2017-12-28 08:29:17.828 Info App: Live stream e5a803bf52ca445cb0e6105f798aed80_546d94ca6827a4e44c8b7afc364593a5 closed successfully
2017-12-28 08:29:17.833 Info HttpServer: HTTP Response 204 to 192.168.1.100. Time: 62ms. http://192.168.1.183:8096/emby/Sessions/Playing/Stopped 
2017-12-28 08:29:17.834 Info Trakt: Playback Stopped
2017-12-28 08:29:33.199 Info HttpServer: HTTP GET http://192.168.1.183:8096/web/strings/en-US.json?v=1514478576871. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64)

Finally if I attempt to start playback immediately, I get forbidden HTTP exceptions. If I wait for 5 -10 minutes and try running the stream, that works fine. My sub only allows for a single stream, so I am guessing that is the reason for forbidden exceptions:

2017-12-28 08:20:59.516 Info HttpServer: HTTP GET http://192.168.1.183:8096/emby/livetv/programs?userId=dc918e0034cc473082461f5fa7a61c3a&fields=overview,channelInfo&sortBy=StartDate&EnableImageTypes=Primary,Thumb&maxStartDate=2017-12-28T18%3A00%3A00Z&minEndDate=2017-12-28T16%3A00%3A00Z&channelIds=0bb13de716bb99b956782447a609ac89%2Cb707ca31ba52e636d6e404e33713c337%2Cf1c3dec3ce62bd41e9fd91686bd50d21%2Ca9816207ed9409b6728c8bd1b810b363%2Ca1f25e606e7adfa78a6fcefda8be8e26. UserAgent: Roku/DVP-8.1 (298.01E04042A)
2017-12-28 08:20:59.538 Info HttpServer: HTTP Response 200 to 192.168.1.100. Time: 22ms. http://192.168.1.183:8096/emby/livetv/programs?userId=dc918e0034cc473082461f5fa7a61c3a&fields=overview,channelInfo&sortBy=StartDate&EnableImageTypes=Primary,Thumb&maxStartDate=2017-12-28T18%3A00%3A00Z&minEndDate=2017-12-28T16%3A00%3A00Z&channelIds=0bb13de716bb99b956782447a609ac89%2Cb707ca31ba52e636d6e404e33713c337%2Cf1c3dec3ce62bd41e9fd91686bd50d21%2Ca9816207ed9409b6728c8bd1b810b363%2Ca1f25e606e7adfa78a6fcefda8be8e26 
2017-12-28 08:20:59.695 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 3.2.60.0
	Command line: C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll C:\Users\media\AppData\Roaming\Emby-Server\system\EmbyServer.dll -noautorunwebapp
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: True
	Processor count: 4
	Program data path: C:\Users\media\AppData\Roaming\Emby-Server\programdata
	Application directory: C:\Users\media\AppData\Roaming\Emby-Server\system
	MediaBrowser.Model.Net.HttpException: Forbidden
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.<SendAsyncInternal>d__20.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.<SendAsync>d__17.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at MediaBrowser.Providers.Manager.ProviderManager.<SaveImage>d__33.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Library.LibraryManager.<ConvertImageToLocal>d__188.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Drawing.ImageProcessor.<ProcessImage>d__29.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at MediaBrowser.Api.Images.ImageService.<GetImageResult>d__24.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.<Execute>d__2.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.<ProcessRequestAsync>d__15.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.<RequestHandler>d__72.MoveNext()
	MediaBrowser.Model.Net.HttpException
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.<SendAsyncInternal>d__20.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpClientManager.CoreHttpClientManager.<SendAsync>d__17.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at MediaBrowser.Providers.Manager.ProviderManager.<SaveImage>d__33.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Library.LibraryManager.<ConvertImageToLocal>d__188.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Drawing.ImageProcessor.<ProcessImage>d__29.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at MediaBrowser.Api.Images.ImageService.<GetImageResult>d__24.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ServiceExecGeneral.<Execute>d__2.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ServiceHandler.<ProcessRequestAsync>d__15.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.<RequestHandler>d__72.MoveNext()
	
2017-12-28 08:20:59.696 Info HttpServer: HTTP Response 500 to 192.168.1.100. Time: 878ms. http://192.168.1.183:8096/emby/Items/f1c3dec3ce62bd41e9fd91686bd50d21/Images/Primary?EnableImageEnhancers=true&tag=f97fff0ee75bf15de24d1a7b88883854&maxheight=150

If you wish to see the entire logs, could you let me know how to provide them to you without putting them on the open world please?

December 28, 2017

Hi, yes, you can PM me complete log files, thanks.

December 28, 2017

Files have been sent to you now. Thank you Luke.

January 3, 2018

@@y3knik is this on a specific channel or all channels?

January 4, 2018

Its only happening on specific channels. I have identified 3 so far that are causing it from the particular provider.

January 4, 2018

How is it playing? is it doing a full transcode, or is it stream copying the original video?

January 4, 2018

I have no idea what that means, sorry!

Can I look at the server dashboard or the log during playback to determine this? Are you just asking if the dashboard says direct streaming or trans-coding when the channel is playing?

Thanks

January 4, 2018

I have no idea what that means, sorry!

Are you just asking if the dashboard says direct streaming or trans-coding when the channel is playing?

Thanks

Yes.

January 5, 2018

It is direct streaming the channel.

January 5, 2018

What happens if you lower the in-app bitrate to force it to transcode?

January 5, 2018

I dropped down the app to 1 Mbps HD which caused a transcode on the server.

With the transcode, the stream ended up dropping out again, however this time, I was able to just hit play and start the stream up again without any http errors like I saw with the direct playing.

January 5, 2018

Ok, can you please follow the process here to submit an in app log:

https://emby.media/community/index.php?/topic/790-how-to-report-a-problem/?view=getnewpost

Then @@ebr will take a look. Thanks.

January 5, 2018

Sorry, another stupid question from me.

Is the in app log the same as the server log files that I snipped in the first post and then PM'ed you initially? Are you just looking for newer versions of the log with transcode happening? Or are you looking for logs from the Roku? Can you point me to the place where I can get Roku logs from please? I dont see in on that link that you posted.

Thank you for your help so far!

January 5, 2018

It's a Roku log and the instructions are at the bottom of that link. Thanks.

January 5, 2018

I am sorry Luke, I have no idea where on that link I should be looking at. Your link is just taking me to the

How to report a problem

This is the only text I see for the app:

Emby App Issues

Please create a topic in the community.
Describe the problem in your own words, including what you're trying to accomplish, and where you're getting stuck.
If you saw error messages, please tell us what they were. If the problem is difficult to explain, please include screenshots that demonstrate the issue.
Include the version of the server that you're using. This information can be found on the front page of the server's web dashboard.

January 5, 2018

Sorry, looks like that link needs to be updated.

Please try this one: https://emby.media/community/index.php?/topic/739-how-to-report-a-problem/

January 5, 2018

Perfect, I see the instructions now, thanks ebr!

I am out this weekend, but I will get this to you Monday.

Do you want me to reproduce with direct play or with transcode or both?

Thank you!

January 5, 2018

Sorry, looks like that link is missing some of the info. Try the bottom of this one:

https://emby.media/community/index.php?/topic/739-how-to-report-a-problem/

I'll sync them up. Thanks.

January 8, 2018

Hello, log from the Roku just got uploaded to your servers with direct play. The user name is Nikhil. The channel playing is HGTV.

If that didnt work or if you need logs with transcoding as well, please let me know.

Thanks!

January 8, 2018

Thanks.

From the app perspective, the stream finished.

Playlist end reached

Does this have something to do with the "loop" option for IPTV?

January 8, 2018

I dont have auto loop enabled on the server. I can try that and report back if you like.

January 9, 2018

I just enabled auto loop and tried, same thing. Would you like me to upload the logs?

January 9, 2018

Yes please, thanks.

January 10, 2018

I uploaded logs from Roku to your servers and also PM'ed you the logs from the server. Let me know if that helps.

January 10, 2018

Ok, if you dont' specifically need the stream loop feature then i would turn that back off.

What about the built-in web app? Are you able to play with that?

Error playing channel on Roku in a sustained manner

Recommended Posts

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

ebr 14959

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

ebr 14959

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

y3knik 14

Link to comment

Share on other sites

Luke 37251

Link to comment

Share on other sites

Create an account or sign in to comment

Create an account

Sign in