Jump to content

Streaming sometimes closes/reopens constantly

bfir3

By bfir3
in General/Windows

 Share

Recommended Posts

bfir3

bfir3   113

Posted
Posted

So I've had this one happen in the past, and I know other users have reported this to me as well. It's when playback of an item stops suddenly before the end of the item. The item's progress is lost as if it was never played. If it is a playlist, the next item will begin playing.

 

For example I was playing episode 209 of the show I'm watching and about 30 minutes into the episode the video stopped and queued the next episode and began to play it. When I went back to the previous episode, it didn't remember the progress I had made.

 

Here's the log from when it just happened:

2017-06-11 16:46:34.0929 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:34.0929 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 1ms. http://server:8896/emby/Sessions/Playing/Progress 
2017-06-11 16:46:37.0621 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:37.0621 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 2ms. http://server:8896/emby/Sessions/Playing/Progress 
2017-06-11 16:46:42.0639 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.0639 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.0639 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 1ms. http://server:8896/emby/Sessions/Playing/Progress 
2017-06-11 16:46:42.0639 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 2ms. http://server:8896/emby/Sessions/Playing/Progress 
2017-06-11 16:46:42.1989 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Stopped. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.1989 Debug App: ReportPlaybackStopped PlaySessionId: 
2017-06-11 16:46:42.1989 Info SessionManager: Playback stopped reported by app Emby Mobile 3.2.19.0 playing Orphans. Stopped at 2544920 ms
2017-06-11 16:46:42.2029 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 4ms. http://server:8896/emby/Sessions/Playing/Stopped 
2017-06-11 16:46:42.2029 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Stopped. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.2029 Debug App: ReportPlaybackStopped PlaySessionId: 
2017-06-11 16:46:42.2029 Info SessionManager: Playback stopped reported by app Emby Mobile 3.2.19.0 playing Orphans. Stopped at 0 ms
2017-06-11 16:46:42.2029 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 5ms. http://server:8896/emby/Sessions/Playing/Stopped 
2017-06-11 16:46:42.2029 Info HttpServer: HTTP POST http://server:8896/emby/Items/3a547273c628855e9d4a8322aa727e57/PlaybackInfo?UserId=9bd5a3fc96674233877b5e43b0c0df26&StartTimeTicks=0&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=1129411765. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.2029 Info App: Profile: Unknown Profile, Path: S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2017-06-11 16:46:42.2029 Info App: Profile: Unknown Profile, Path: S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2017-06-11 16:46:42.2029 Info App: Profile: Unknown Profile, Path: S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2017-06-11 16:46:42.2029 Info HttpServer: HTTP Response 200 to 104.163.183.58. Time: 3ms. http://server:8896/emby/Items/3a547273c628855e9d4a8322aa727e57/PlaybackInfo?UserId=9bd5a3fc96674233877b5e43b0c0df26&StartTimeTicks=0&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=1129411765 
2017-06-11 16:46:42.2029 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 3.2.19.0
	Command line: C:\Users\Administrator\AppData\Roaming\Emby-Server\system\MediaBrowser.ServerApplication.exe -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	Processor count: 6
	Program data path: C:\Users\Administrator\AppData\Roaming\Emby-Server
	Application directory: C:\Users\Administrator\AppData\Roaming\Emby-Server\system
	System.Net.Sockets.SocketException (0x80004005): An existing connection was forcibly closed by the remote host
	   at System.Net.Sockets.Socket.EndSend(IAsyncResult asyncResult)
	   at SocketHttpListener.Net.HttpResponseStream.EndWriteCore(IAsyncResult asyncResult)
	   at System.IO.Stream.<>c.<BeginEndWriteAsync>b__53_1(Stream stream, IAsyncResult asyncResult)
	   at System.Threading.Tasks.TaskFactory`1.FromAsyncTrimPromise`1.Complete(TInstance thisRef, Func`3 endMethod, IAsyncResult asyncResult, Boolean requiresSynchronization)
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at SocketHttpListener.Net.HttpResponseStream.<CopyToInternalAsyncWithSyncRead>d__53.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at SocketHttpListener.Net.HttpResponseStream.<TransmitFileManaged>d__52.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.FileWriter.<WriteToAsync>d__57.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ResponseHelper.<WriteToResponse>d__0.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   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.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.<RequestHandler>d__75.MoveNext()
	System.Net.Sockets.SocketException
	   at System.Net.Sockets.Socket.EndSend(IAsyncResult asyncResult)
	   at SocketHttpListener.Net.HttpResponseStream.EndWriteCore(IAsyncResult asyncResult)
	   at System.IO.Stream.<>c.<BeginEndWriteAsync>b__53_1(Stream stream, IAsyncResult asyncResult)
	   at System.Threading.Tasks.TaskFactory`1.FromAsyncTrimPromise`1.Complete(TInstance thisRef, Func`3 endMethod, IAsyncResult asyncResult, Boolean requiresSynchronization)
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at SocketHttpListener.Net.HttpResponseStream.<CopyToInternalAsyncWithSyncRead>d__53.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at SocketHttpListener.Net.HttpResponseStream.<TransmitFileManaged>d__52.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.FileWriter.<WriteToAsync>d__57.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ResponseHelper.<WriteToResponse>d__0.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   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.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.<RequestHandler>d__75.MoveNext()
	
2017-06-11 16:46:42.2029 Info HttpServer: HTTP Response 500 to 104.163.183.58. Time: 244364ms. http://server:8896/emby/Videos/ff3a2809506eeef5fa2e3a9d7b0a3017/stream.mp4?Static=true&mediaSourceId=ff3a2809506eeef5fa2e3a9d7b0a3017&deviceId=93b69ccbb2c89455cef1b49c2856d94df192fbb5&Tag=e757f905f163ad5e252e0713370ce52b Headers: Accept-Ranges=bytes, Content-Range=bytes 2147566077-4295049723/4586857445, ETag="7c36fa949d731caf688ece59df583e42", Age=4163128, Last-Modified=Mon, 24 Apr 2017 16:17:09 GMT, Cache-Control=public, max-age=31536000, Expires=Mon, 11 Jun 2018 20:42:38 GMT, Server=Mono-HTTPAPI/1.1, UPnP/1.0 DLNADOC/1.50, Content-Type=video/mp4, Date=Sun, 11 Jun 2017 20:42:38 GMT, Content-Length=2147483647, Connection=close, Status=206
2017-06-11 16:46:42.3679 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.3679 Info HttpServer: HTTP GET http://server:8896/emby/Videos/3a547273c628855e9d4a8322aa727e57/stream.mp4?Static=true&mediaSourceId=3a547273c628855e9d4a8322aa727e57&deviceId=93b69ccbb2c89455cef1b49c2856d94df192fbb5&Tag=d53621198dd9b3dcea608ce5205073ff. X-Real-IP=104.163.183.58, Host=server, X-Forwarded-For=104.163.183.58, Connection=close, Accept-Encoding=identity;q=1, *;q=0, User-Agent=Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36, Accept=*/*, Referer=http://server/web/videoosd.html, Accept-Language=en-US,en;q=0.8, Range=bytes=0-
2017-06-11 16:46:42.3679 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 2ms. http://server:8896/emby/Sessions/Playing/Progress 
2017-06-11 16:46:42.4459 Info HttpResultFactory: Setting range response values for S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4. RangeRequest: bytes=0- Content-Length: 4706508093, Content-Range: bytes 0-4706508092/4706508093
2017-06-11 16:46:42.4459 Info HttpResultFactory: Transmit file S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4
2017-06-11 16:46:42.4989 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 3.2.19.0
	Command line: C:\Users\Administrator\AppData\Roaming\Emby-Server\system\MediaBrowser.ServerApplication.exe -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	64-Bit OS: True
	64-Bit Process: True
	Processor count: 6
	Program data path: C:\Users\Administrator\AppData\Roaming\Emby-Server
	Application directory: C:\Users\Administrator\AppData\Roaming\Emby-Server\system
	System.Net.Sockets.SocketException (0x80004005): An established connection was aborted by the software in your host machine
	   at System.Net.Sockets.Socket.Send(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
	   at Emby.Common.Implementations.Net.SocketStream.Write(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.InternalWrite(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.WriteCore(Byte[] buffer, Int32 offset, Int32 size)
	   at System.IO.Stream.InternalCopyTo(Stream destination, Int32 bufferSize)
	   at SocketHttpListener.Net.HttpResponseStream.<TransmitFileManaged>d__52.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.FileWriter.<WriteToAsync>d__57.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ResponseHelper.<WriteToResponse>d__0.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   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.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.<RequestHandler>d__75.MoveNext()
	System.Net.Sockets.SocketException
	   at System.Net.Sockets.Socket.Send(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
	   at Emby.Common.Implementations.Net.SocketStream.Write(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.InternalWrite(Byte[] buffer, Int32 offset, Int32 count)
	   at SocketHttpListener.Net.HttpResponseStream.WriteCore(Byte[] buffer, Int32 offset, Int32 size)
	   at System.IO.Stream.InternalCopyTo(Stream destination, Int32 bufferSize)
	   at SocketHttpListener.Net.HttpResponseStream.<TransmitFileManaged>d__52.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.FileWriter.<WriteToAsync>d__57.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.Services.ResponseHelper.<WriteToResponse>d__0.MoveNext()
	--- End of stack trace from previous location where exception was thrown ---
	   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   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.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
	   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
	   at Emby.Server.Implementations.HttpServer.HttpListenerHost.<RequestHandler>d__75.MoveNext()
	
2017-06-11 16:46:42.4989 Info HttpServer: HTTP Response 500 to 104.163.183.58. Time: 131ms. http://server:8896/emby/Videos/3a547273c628855e9d4a8322aa727e57/stream.mp4?Static=true&mediaSourceId=3a547273c628855e9d4a8322aa727e57&deviceId=93b69ccbb2c89455cef1b49c2856d94df192fbb5&Tag=d53621198dd9b3dcea608ce5205073ff Headers: Accept-Ranges=bytes, Content-Range=bytes 0-4706508092/4706508093, ETag="d611d3483d54e7e6af2fb22e2df3d0a8", Age=4171423, Last-Modified=Mon, 24 Apr 2017 14:02:59 GMT, Cache-Control=public, max-age=31536000, Expires=Mon, 11 Jun 2018 20:46:42 GMT, Server=Mono-HTTPAPI/1.1, UPnP/1.0 DLNADOC/1.50, Content-Type=video/mp4, Date=Sun, 11 Jun 2017 20:46:42 GMT, Content-Length=4706508093, Connection=close, Status=206
2017-06-11 16:46:42.4989 Info HttpServer: HTTP GET http://server:8896/emby/Videos/3a547273c628855e9d4a8322aa727e57/stream.mp4?Static=true&mediaSourceId=3a547273c628855e9d4a8322aa727e57&deviceId=93b69ccbb2c89455cef1b49c2856d94df192fbb5&Tag=d53621198dd9b3dcea608ce5205073ff. X-Real-IP=104.163.183.58, Host=server, X-Forwarded-For=104.163.183.58, Connection=close, Accept-Encoding=identity;q=1, *;q=0, User-Agent=Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36, Accept=*/*, Referer=http://server/web/videoosd.html, Accept-Language=en-US,en;q=0.8, Range=bytes=4702339072-4706508092, If-Range="d611d3483d54e7e6af2fb22e2df3d0a8"
2017-06-11 16:46:42.4989 Info HttpResultFactory: Setting range response values for S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4. RangeRequest: bytes=4702339072-4706508092 Content-Length: 4169021, Content-Range: bytes 4702339072-4706508092/4706508093
2017-06-11 16:46:42.5449 Info HttpServer: HTTP Response 206 to 104.163.183.58. Time: 45ms. http://server:8896/emby/Videos/3a547273c628855e9d4a8322aa727e57/stream.mp4?Static=true&mediaSourceId=3a547273c628855e9d4a8322aa727e57&deviceId=93b69ccbb2c89455cef1b49c2856d94df192fbb5&Tag=d53621198dd9b3dcea608ce5205073ff Headers: Accept-Ranges=bytes, Content-Range=bytes 4702339072-4706508092/4706508093, ETag="d611d3483d54e7e6af2fb22e2df3d0a8", Age=4171423, Last-Modified=Mon, 24 Apr 2017 14:02:59 GMT, Cache-Control=public, max-age=31536000, Expires=Mon, 11 Jun 2018 20:46:42 GMT, Server=Mono-HTTPAPI/1.1, UPnP/1.0 DLNADOC/1.50, Content-Type=video/mp4, Date=Sun, 11 Jun 2017 20:46:42 GMT, Content-Length=4169021, Connection=close, Status=206
2017-06-11 16:46:42.6860 Info HttpServer: HTTP GET http://server:8896/emby/Videos/3a547273c628855e9d4a8322aa727e57/stream.mp4?Static=true&mediaSourceId=3a547273c628855e9d4a8322aa727e57&deviceId=93b69ccbb2c89455cef1b49c2856d94df192fbb5&Tag=d53621198dd9b3dcea608ce5205073ff. X-Real-IP=104.163.183.58, Host=server, X-Forwarded-For=104.163.183.58, Connection=close, Accept-Encoding=identity;q=1, *;q=0, User-Agent=Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36, Accept=*/*, Referer=http://server/web/videoosd.html, Accept-Language=en-US,en;q=0.8, Range=bytes=96462-2147580108, If-Range="d611d3483d54e7e6af2fb22e2df3d0a8"
2017-06-11 16:46:42.6860 Info HttpResultFactory: Setting range response values for S:\TV\19-2 (2014)\Season 2\19-2 (2014).s02e10.Bridges WEBDL-1080p.mp4. RangeRequest: bytes=96462-2147580108 Content-Length: 2147483647, Content-Range: bytes 96462-2147580108/4706508093
2017-06-11 16:46:42.7490 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-11 16:46:42.7490 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 6ms. http://server:8896/emby/Sessions/Playing
Link to comment
Share on other sites
Luke Emby Team

Luke   37008

Posted
Posted

if they play the same file again, does it happen again at the same spot? can you reproduce it with the same file?

Link to comment
Share on other sites
bfir3

bfir3   113

Posted
  • Author
Posted

if they play the same file again, does it happen again at the same spot? can you reproduce it with the same file?

 

I will test this tomorrow. I was able to seek to the spot it ended at and continue from there to the end.

Link to comment
Share on other sites
ownerer

ownerer   0

Posted
Posted

This issue (or a very similar one at least) had been raised over a year ago in this topic: https://emby.media/community/index.php?/topic/34464-ffmpeg-constantly-closing-and-reopening-for-transcoding/

As per @@Luke's request, I've opened a new topic.

 

The issue appears very randomly, it seems.

The exception that is thrown is this: "System.Net.Sockets.SocketException (0x80004005): An existing connection was forcibly closed by the remote host"

  • The one constant is that it only happens when streaming.
    I suppose that makes sense since Direct Playing media probably doesn't use sockets :).
  • The client doesn't matter, I've seen it happen in both Chrome and Kodi.
  • To be clear: I have seen this happen both with AND without transcoding (direct stream), though if I'm not mistaken the latter does still use FFMpeg to remux?
  • I've seen it happen both on my local network when at home and internet when I was away on holiday.

 

When the issue arises it manifests itself as a constant reloading of the media player, i.e. the loading animation is shown, disappears again, is shown again and so on and so on.

The first time I saw this happen I was trying to watch a tv show, and the weird thing was:

  • Emby, or whatever library is causing the issue, failed to play the episode I selected.
  • I stopped the loading loop, and tried again with the same episode.
  • Emby automatically skipped this episode, even though I had not watched it and continued to the next!! Where it failed again.
  • I stopped the loading loop again, selected the first episode again and now it skipped TWO unwatched episodes, just to fail on the third.
  • This pattern continued until it was just skipping to the last episode of the season, where it was still failing.

 

I have also seen the issue happen with just one episode, i.e. without having Emby skip it the next time I tried playing it, even though it was still failing.

 

I'm finding it very hard to reproduce, sometimes it happens, other times it doesn't and the environment is always the same, to my knowledge.

All of the log files attached have the error I'm talking about.

Having just quickly scanned through them myself, I also noticed that even streaming music suffers from this.

 

If you need any further infiormation, please let me know, I'm a .NET engineer myself so you can talk tech talk with me if need be :)

 

server-63632649600.txt server-63632770790.txt server-63632882426.txt

Link to comment
Share on other sites
CBers

CBers   6766

Posted
Posted

I raised my "Playback stopped" thread for a similar issue with the Android TV app.

 

Post 5 contains the same error:

System.Net.Sockets.SocketException (0x80004005): An existing connection was forcibly closed by the remote host
Link to comment
Share on other sites
Luke Emby Team

Luke   37008

Posted
Posted

@@ownerer, thanks for the info. Since there are three logs and one is very large, what specific playback scenarios should we be looking at?

 

For example, "Around 4pm on Tuesday I tried to play video X from app Y using device Z. I expected A to happen but instead saw B, C and D. Thanks !

Link to comment
Share on other sites
ownerer

ownerer   0

Posted
Posted (edited)

@@Luke

for example in the ...790 log, 2017-06-11 18:19 timestamp, was trying to play an episode in Chrome on my Windows 7 machine.

I basically saw what I described in my original post: episode never starts to play and seems to be reloading indefinitely.

If you search those logs for "socketexception", almost all occurrences are examples of this.

Really any hit where multiple occurrences of this exception happen within the same second almost.

This is the file's media info btw.

Let me know if I can provide you with anything more!

Format                                   : Matroska
Format version                           : Version 4 / Version 2
File size                                : 1.31 GiB
Duration                                 : 41 min 56 s
Overall bit rate                         : 4 463 kb/s
Encoded date                             : UTC 2016-11-03 19:40:24
Writing application                      : mkvmerge v9.3.1 ('Mask Machine') 64bit
Writing library                          : libebml v1.3.4 + libmatroska v1.4.5
Cover                                    : Yes
Attachments                              : CronosPro-SemiboldIt.ttf / CronosPro-Bold.ttf / CronosPro-Semibold.ttf / small_cover.jpg / cover.jpg / cover_land.jpg / small_cover_land.jpg

Video
ID                                       : 1
Format                                   : AVC
Format/Info                              : Advanced Video Codec
Format profile                           : High@L3.1
Format settings, CABAC                   : No
Format settings, ReFrames                : 2 frames
Codec ID                                 : V_MPEG4/ISO/AVC
Duration                                 : 41 min 56 s
Bit rate                                 : 4 075 kb/s
Width                                    : 1 280 pixels
Height                                   : 718 pixels
Display aspect ratio                     : 16:9
Frame rate mode                          : Constant
Frame rate                               : 23.976 (23976/1000) FPS
Color space                              : YUV
Chroma subsampling                       : 4:2:0
Bit depth                                : 8 bits
Scan type                                : Progressive
Bits/(Pixel*Frame)                       : 0.185
Stream size                              : 1.19 GiB (91%)
Language                                 : English
Default                                  : Yes
Forced                                   : No
Color range                              : Limited
Color primaries                          : BT.709
Transfer characteristics                 : BT.709
Matrix coefficients                      : BT.709

Audio
ID                                       : 2
Format                                   : AC-3
Format/Info                              : Audio Coding 3
Mode extension                           : CM (complete main)
Format settings, Endianness              : Big
Codec ID                                 : A_AC3
Duration                                 : 41 min 56 s
Bit rate mode                            : Constant
Bit rate                                 : 384 kb/s
Channel(s)                               : 6 channels
Channel positions                        : Front: L C R, Side: L R, LFE
Sampling rate                            : 48.0 kHz
Frame rate                               : 31.250 FPS (1536 spf)
Bit depth                                : 16 bits
Compression mode                         : Lossy
Stream size                              : 115 MiB (9%)
Language                                 : English
Default                                  : Yes
Forced                                   : No

Text
ID                                       : 3
Format                                   : ASS
Codec ID                                 : S_TEXT/ASS
Codec ID/Info                            : Advanced Sub Station Alpha
Duration                                 : 40 min 52 s
Bit rate                                 : 145 b/s
Count of elements                        : 736

@@CBers and @bfir3: good to know I'm not alone then! :)

Edited by ownerer
Link to comment
Share on other sites
Luke Emby Team

Luke   37008

Posted
Posted

Those are not indicative of any problem. We need to get an error message from the app you're playing from. What app are you playing from? Thanks.

Link to comment
Share on other sites
bfir3

bfir3   113

Posted
  • Author
Posted

What app are you playing from?

 

He mentioned using the Chrome browser so I would imagine he's using the web app.

Link to comment
Share on other sites
ownerer

ownerer   0

Posted
Posted

@@Luke, as @@bfir3 said, these examples happened when playing through the web interface in Chrome.

There WAS no error message client-side, or I would have mentioned it.

The ONLY thing that visibly happened was exactly what I described: indefinite loading loop trying to start playing the episode.

 

The first time I saw this problem however it was in Kodi on my Android phone, which uses the Emby sync add-on.

I think the error message there was something along the lines of "failed to play x media".

Maybe Kodi/the add-on logs this issue? Any idea where I could find the logs on an Android system, if they exist?

This was before any of the logs I attached in my first post btw, I have no server logs anymore from that time.

Link to comment
Share on other sites
bfir3

bfir3   113

Posted
  • Author
Posted

Episode played till the end this time. I did have the same issue on a different episode since then though. This time it skipped to the next episode after about 30 minutes of playback.

Link to comment
Share on other sites
Luke Emby Team

Luke   37008

Posted
Posted

Have you updated to 3.2.20? If so can you please provide a new log example with that? thanks !

Link to comment
Share on other sites
ownerer

ownerer   0

Posted
Posted

Have you updated to 3.2.20? If so can you please provide a new log example with that? thanks !

I always update as soon as new releases come out, so yes :)

 

I'll have to mess around with it again to see if I can make it crash.

Like I said before, the error appears to be random, so not easily reproduced.

I'll keep you posted!

Link to comment
Share on other sites
bfir3

bfir3   113

Posted
  • Author
Posted

Had the issue on two episodes back to back yesterday. The first one ended when there was about 3 minutes left out of the 30 minute runtime. It marked as watched and skipped to the next episode. I unmarked it as watched and finished playing it, then it went to the next episode which then stopped after about 20 minutes of its 30 minute runtime. This is all on local network using the wep app from Chrome browser on windows.

  • Like 1
Link to comment
Share on other sites
ownerer

ownerer   0

Posted
Posted

Had the issue on two episodes back to back yesterday. The first one ended when there was about 3 minutes left out of the 30 minute runtime. It marked as watched and skipped to the next episode. I unmarked it as watched and finished playing it, then it went to the next episode which then stopped after about 20 minutes of its 30 minute runtime. This is all on local network using the wep app from Chrome browser on windows.

Can you attach your logs?

So we can document this as much as possible for @@Luke and the devs to have a look at.

I'm finding it really hard to reproduce this error.

Yesterday I was streaming over the internet and everything just worked, with transcoding.

I'm just waiting for something to break again essentially.

Link to comment
Share on other sites
bfir3

bfir3   113

Posted
  • Author
Posted

Can you attach your logs?

So we can document this as much as possible for @@Luke and the devs to have a look at.

I'm finding it really hard to reproduce this error.

Yesterday I was streaming over the internet and everything just worked, with transcoding.

I'm just waiting for something to break again essentially.

Yeah I will go through my logs and post them tonight if I have time. It is very hard to reproduce and sometimes I can go days without having the issue. Other times it's like I said before and I get the problem multiple times in one night. The worst part about it is that sometimes it will cut off near the end of the episode in a spot that seems like it might be the real ending of the show. This is what happened on Monday, I had to go back to verify that the episode hadn't played to completion.

Link to comment
Share on other sites
ownerer

ownerer   0

Posted
Posted

Yeah I will go through my logs and post them tonight if I have time. It is very hard to reproduce and sometimes I can go days without having the issue. Other times it's like I said before and I get the problem multiple times in one night. The worst part about it is that sometimes it will cut off near the end of the episode in a spot that seems like it might be the real ending of the show. This is what happened on Monday, I had to go back to verify that the episode hadn't played to completion.

Yeah tell me about it, can't seem to reproduce it at all lately :/

I've never had your situation though, mine just refuse to play outright when the issue occurs...

Link to comment
Share on other sites
bfir3

bfir3   113

Posted
  • Author
Posted (edited)

Here is the relevant log file. I started playing the first episode at 20:03, so look for this line:

2017-06-25 20:03:14.6456 Info HttpServer: HTTP POST http://server:8896/emby/Items/866a8c0d49e6fc45cb27ec1ef85ce270/PlaybackInfo?UserId=31dc038d1bd74664a6b44163cf418000&StartTimeTicks=2442012500&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=505263158. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36

You can search for "Silicon" or "valley" and it should bring up the relevant log lines for when playback started/stopped. 

 

Here are the log lines from the first time it stopped the playback:

2017-06-25 20:27:08.6281 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.6281 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 2ms. http://server:8896/emby/Sessions/Playing/Progress 
2017-06-25 20:27:08.7211 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Stopped. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.7211 Debug App: ReportPlaybackStopped PlaySessionId: 
2017-06-25 20:27:08.7211 Info SessionManager: Playback stopped reported by app Emby Mobile 3.2.19.0.04 playing The Keenan Vortex. Stopped at 1811852 ms
2017-06-25 20:27:08.7211 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 4ms. http://server:8896/emby/Sessions/Playing/Stopped 
2017-06-25 20:27:08.7211 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Stopped. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.7211 Debug App: ReportPlaybackStopped PlaySessionId: 
2017-06-25 20:27:08.7211 Info HttpServer: HTTP POST http://server:8896/emby/Items/cbfbbc7529353a5a22bd9fbce8c7b35b/PlaybackInfo?UserId=31dc038d1bd74664a6b44163cf418000&StartTimeTicks=0&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=505263158. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.7211 Info SessionManager: Playback stopped reported by app Emby Mobile 3.2.19.0.04 playing The Keenan Vortex. Stopped at 0 ms
2017-06-25 20:27:08.7211 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 5ms. http://server:8896/emby/Sessions/Playing/Stopped 
2017-06-25 20:27:08.7211 Info App: Profile: Unknown Profile, Path: S:\TV\Silicon Valley\Season 4\Silicon Valley.s04e09.Hooli-Con WEBDL-720p.mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2017-06-25 20:27:08.7211 Info App: Profile: Unknown Profile, Path: S:\TV\Silicon Valley\Season 4\Silicon Valley.s04e09.Hooli-Con WEBDL-720p.mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2017-06-25 20:27:08.7211 Info App: Profile: Unknown Profile, Path: S:\TV\Silicon Valley\Season 4\Silicon Valley.s04e09.Hooli-Con WEBDL-720p.mp4, isEligibleForDirectPlay: True, isEligibleForDirectStream: True
2017-06-25 20:27:08.7211 Info HttpServer: HTTP Response 200 to 104.163.183.58. Time: 5ms. http://server:8896/emby/Items/cbfbbc7529353a5a22bd9fbce8c7b35b/PlaybackInfo?UserId=31dc038d1bd74664a6b44163cf418000&StartTimeTicks=0&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=505263158 
2017-06-25 20:27:08.7211 Info HttpServer: HTTP GET http://server:8896/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2017-06-25T00%3A27%3A03.270Z. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.7401 Info HttpServer: HTTP Response 200 to 104.163.183.58. Time: 6ms. http://server:8896/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2017-06-25T00%3A27%3A03.270Z 
2017-06-25 20:27:08.9111 Info HttpServer: HTTP POST http://server:8896/emby/Sessions/Playing/Progress. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.9111 Info HttpServer: HTTP GET http://server:8896/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2017-06-25T00%3A27%3A03.275Z. UserAgent: Mozilla/5.0 (Windows NT 6.1; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.110 Safari/537.36
2017-06-25 20:27:08.9111 Info HttpServer: HTTP Response 204 to 104.163.183.58. Time: 3ms. http://server:8896/emby/Sessions/Playing/Progress

The strange thing is that the episode is reported as being stopped twice. The first time with some timestamp, and the second time with a 0ms timestamp.

 

After this, playback continues to the next episode. You will see shortly after that I stop that episode and go back to play the previous one. It starts from the beginning (is marked as watched) so I have to skip ahead to where it stopped. After the episode is done, playback to the next episode continues normally, and then playback is stopped again at some time during that next episode.

 

edit: Now that I look at it, I have a lot of PlaySessionIds that are empty for ReportPlaybackStopped calls...dunno if this affects anything or why that is happening.

server-63633945600.zip

Edited by bfir3
Link to comment
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
 Share
Go to topic listing
×
×
  • Create New...