alexxx 2 Posted April 29, 2017 Posted April 29, 2017 (edited) Hello, I noticed, that the playback in the browser (I tested Chrome 55 and the current Edge) sometimes only plays the first couple of seconds (usually around 30 to 60 secs) and then stops. When I wait, the playback continues mostly after about one minute but still stops every few seconds (and then another half a minute of waiting) In the webserver log I see a normal ts-part-request which is answered by emby with an 502 error code. Is saw this error with the current version of emby (3.2.13) but also with the previous version. Unfortunately, I can not remember exactly when it started. The emby server log says, that the socket was closed unexpectedly: 2017-04-29 10:44:34.9899 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/Users/c28f78848af043a39964430d587cf1af/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.0011 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 11ms. http://emby.mydomain.com:8096/emby/Users/c28f78848af043a39964430d587cf1af/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7 2017-04-29 10:44:35.0436 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/Users/c28f78848af043a39964430d587cf1af/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/Intros. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.0451 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 2ms. http://emby.mydomain.com:8096/emby/Users/c28f78848af043a39964430d587cf1af/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/Intros 2017-04-29 10:44:35.1305 Info HttpServer: HTTP POST http://emby.mydomain.com:8096/emby/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/PlaybackInfo?UserId=c28f78848af043a39964430d587cf1af&StartTimeTicks=0&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=34224599. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.1325 Info App: Profile: Unknown Profile, Path: /moviepath/2011/contagion -= dt eng =- by pebble.mkv, isEligibleForDirectPlay: True, isEligibleForDirectStream: True 2017-04-29 10:44:35.1325 Info App: Profile: Unknown Profile, No direct play profiles found for Path: /moviepath/2011/contagion -= dt eng =- by pebble.mkv 2017-04-29 10:44:35.1325 Info App: Profile: Unknown Profile, Path: /moviepath/2011/contagion -= dt eng =- by pebble.mkv, isEligibleForDirectPlay: True, isEligibleForDirectStream: True 2017-04-29 10:44:35.1325 Info App: Profile: Unknown Profile, No direct play profiles found for Path: /moviepath/2011/contagion -= dt eng =- by pebble.mkv 2017-04-29 10:44:35.1325 Info App: Profile: Unknown Profile, Path: /moviepath/2011/contagion -= dt eng =- by pebble.mkv, isEligibleForDirectPlay: True, isEligibleForDirectStream: True 2017-04-29 10:44:35.1325 Info App: Profile: Unknown Profile, No direct play profiles found for Path: /moviepath/2011/contagion -= dt eng =- by pebble.mkv 2017-04-29 10:44:35.1339 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 3ms. http://emby.mydomain.com:8096/emby/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/PlaybackInfo?UserId=c28f78848af043a39964430d587cf1af&StartTimeTicks=0&ForceDirectPlayRemoteMediaSource=true&MaxStreamingBitrate=34224599 2017-04-29 10:44:35.4421 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/Videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/c7c42ded1a0fca4a18b6a9a918a7b4a7/Subtitles/3/0/Stream.vtt. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.4501 Warn SubtitleEncoder: Unrecognized line in srt: IST FORSYTHIE. 2017-04-29 10:44:35.4508 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 9ms. http://emby.mydomain.com:8096/emby/Videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/c7c42ded1a0fca4a18b6a9a918a7b4a7/Subtitles/3/0/Stream.vtt 2017-04-29 10:44:35.4876 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/master.m3u8?DeviceId=ff69f89d543f345b125d5209c9b69340fd509aaa&MediaSourceId=c7c42ded1a0fca4a18b6a9a918a7b4a7&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=1&VideoBitrate=33840599&AudioBitrate=384000&Level=51&Profile=high&PlaySessionId=2555c3c3e9df40c1a4c9649825b5d666&CopyTimestamps=false&TranscodingMaxAudioChannels=6&EnableSubtitlesInManifest=false&Tag=63c5d48c8c8a700d8fdc848be0bd4075&RequireAvc=true&RequireNonAnamorphic=false&DeInterlace=false&SegmentContainer=ts&MinSegments=2&BreakOnNonKeyFrames=True. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.4895 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 2ms. http://emby.mydomain.com:8096/emby/videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/master.m3u8?DeviceId=ff69f89d543f345b125d5209c9b69340fd509aaa&MediaSourceId=c7c42ded1a0fca4a18b6a9a918a7b4a7&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=1&VideoBitrate=33840599&AudioBitrate=384000&Level=51&Profile=high&PlaySessionId=2555c3c3e9df40c1a4c9649825b5d666&CopyTimestamps=false&TranscodingMaxAudioChannels=6&EnableSubtitlesInManifest=false&Tag=63c5d48c8c8a700d8fdc848be0bd4075&RequireAvc=true&RequireNonAnamorphic=false&DeInterlace=false&SegmentContainer=ts&MinSegments=2&BreakOnNonKeyFrames=True 2017-04-29 10:44:35.5245 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/main.m3u8?DeviceId=ff69f89d543f345b125d5209c9b69340fd509aaa&MediaSourceId=c7c42ded1a0fca4a18b6a9a918a7b4a7&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=1&VideoBitrate=33840599&AudioBitrate=384000&Level=51&Profile=high&PlaySessionId=2555c3c3e9df40c1a4c9649825b5d666&CopyTimestamps=false&TranscodingMaxAudioChannels=6&EnableSubtitlesInManifest=false&Tag=63c5d48c8c8a700d8fdc848be0bd4075&RequireAvc=true&RequireNonAnamorphic=false&DeInterlace=false&SegmentContainer=ts&MinSegments=2&BreakOnNonKeyFrames=True. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.5336 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 9ms. http://emby.mydomain.com:8096/emby/videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/main.m3u8?DeviceId=ff69f89d543f345b125d5209c9b69340fd509aaa&MediaSourceId=c7c42ded1a0fca4a18b6a9a918a7b4a7&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=1&VideoBitrate=33840599&AudioBitrate=384000&Level=51&Profile=high&PlaySessionId=2555c3c3e9df40c1a4c9649825b5d666&CopyTimestamps=false&TranscodingMaxAudioChannels=6&EnableSubtitlesInManifest=false&Tag=63c5d48c8c8a700d8fdc848be0bd4075&RequireAvc=true&RequireNonAnamorphic=false&DeInterlace=false&SegmentContainer=ts&MinSegments=2&BreakOnNonKeyFrames=True 2017-04-29 10:44:35.7596 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/hls1/main/0.ts?DeviceId=ff69f89d543f345b125d5209c9b69340fd509aaa&MediaSourceId=c7c42ded1a0fca4a18b6a9a918a7b4a7&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=1&VideoBitrate=33840599&AudioBitrate=384000&Level=51&Profile=high&PlaySessionId=2555c3c3e9df40c1a4c9649825b5d666&CopyTimestamps=false&TranscodingMaxAudioChannels=6&EnableSubtitlesInManifest=false&Tag=63c5d48c8c8a700d8fdc848be0bd4075&RequireAvc=true&RequireNonAnamorphic=false&DeInterlace=false&SegmentContainer=ts&MinSegments=2&BreakOnNonKeyFrames=True. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:35.7728 Debug App: Starting transcoding because currentTranscodingIndex=null 2017-04-29 10:44:35.7819 Info App: /var/ffmpeg/ffmpeg-git-20161204-64bit-static/ffmpeg -noaccurate_seek -f matroska -codec:0 h264 -codec:1 ac3 -codec:2 dts -i file:"/moviepath/2011/contagion -= dt eng =- by pebble.mkv" -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 copy -bsf:v h264_mp4toannexb -copyts -vsync -1 -codec:a:0 aac -strict experimental -ac 6 -ab 384000 -f segment -max_delay 5000000 -avoid_negative_ts disabled -start_at_zero -segment_time 6 -individual_header_trailer 0 -break_non_keyframes 1 -segment_format mpegts -segment_list_type m3u8 -segment_start_number 0 -segment_list "/mnt/milchstrasse/emby/transcoding-temp/bc953d3c3b59e7dea17f80729c306d63.m3u8" -y "/mnt/milchstrasse/emby/transcoding-temp/bc953d3c3b59e7dea17f80729c306d63%d.ts" 2017-04-29 10:44:36.1049 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/Users/c28f78848af043a39964430d587cf1af/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:36.1181 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 13ms. http://emby.mydomain.com:8096/emby/Users/c28f78848af043a39964430d587cf1af/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7 2017-04-29 10:44:36.4800 Info App: returning /mnt/milchstrasse/emby/transcoding-temp/bc953d3c3b59e7dea17f80729c306d630.ts 2017-04-29 10:44:55.8447 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/Images/Backdrop/1?tag=e34fd5d8abe6b0d23a534bca97cbdea0&maxWidth=1440&quality=80. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:44:55.9346 Info HttpResultFactory: Transmit file /mnt/milchstrasse/emby/cache/temp/c7ce8a9506694bda9835d702eed88fe7.jpg 2017-04-29 10:44:55.9359 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 91ms. http://emby.mydomain.com:8096/emby/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/Images/Backdrop/1?tag=e34fd5d8abe6b0d23a534bca97cbdea0&maxWidth=1440&quality=80 2017-04-29 10:44:59.1373 Info HttpResultFactory: Transmit file /mnt/milchstrasse/emby/transcoding-temp/bc953d3c3b59e7dea17f80729c306d630.ts 2017-04-29 10:44:59.1529 Error HttpServer: Error processing request *** Error Report *** Version: 3.2.13.0 Command line: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe -programdata /var/lib/emby-server -restartpath /usr/lib/emby-server/restart.sh Operating system: Unix 4.9.0.1 64-Bit OS: True 64-Bit Process: True Mono: 4.6.2 (Debian 4.6.2.7+dfsg-1) Processor count: 8 Program data path: /var/lib/emby-server Application directory: /usr/lib/emby-server/bin System.IO.IOException: Unable to write data to the transport connection: The socket has been shut down. ---> System.Net.Sockets.SocketException: The socket has been shut down at System.Net.Sockets.Socket.EndSend (System.IAsyncResult result) [0x00033] in <bd46d4d4f7964dfa9beea098499ab597>:0 at System.Net.Sockets.NetworkStream.EndWrite (System.IAsyncResult asyncResult) [0x0005f] in <bd46d4d4f7964dfa9beea098499ab597>:0 --- End of inner exception stack trace --- at System.Net.Sockets.NetworkStream.EndWrite (System.IAsyncResult asyncResult) [0x000af] in <bd46d4d4f7964dfa9beea098499ab597>:0 at System.IO.Stream.<BeginEndWriteAsync>m__8 (System.IO.Stream stream, System.IAsyncResult asyncResult) [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at (wrapper delegate-invoke) System.Func`3[System.IO.Stream,System.IAsyncResult,System.Threading.Tasks.VoidTaskResult]:invoke_TResult_T1_T2 (System.IO.Stream,System.IAsyncResult) at System.Threading.Tasks.TaskFactory`1+FromAsyncTrimPromise`1[TResult,TInstance].Complete (TInstance thisRef, System.Func`3[T1,T2,TResult] endMethod, System.IAsyncResult asyncResult, System.Boolean requiresSynchronization) [0x00002] in <8f2c484307284b51944a1a13a14c0266>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at System.IO.Stream+<CopyToAsyncInternal>c__async0.MoveNext () [0x0009b] in <8f2c484307284b51944a1a13a14c0266>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at SocketHttpListener.Net.ResponseStream+<TransmitFileManaged>c__async2.MoveNext () [0x00215] in <be8b138bd9e244198adda21dfe43a054>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.HttpServer.FileWriter+<WriteToAsync>c__async0.MoveNext () [0x0012c] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.Services.ResponseHelper+<WriteToResponseInternal>c__async0.MoveNext () [0x003c7] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.Services.ServiceHandler+<ProcessRequestAsync>c__async1.MoveNext () [0x002a6] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.HttpServer.HttpListenerHost+<RequestHandler>c__async0.MoveNext () [0x00674] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 System.IO.IOException at System.Net.Sockets.NetworkStream.EndWrite (System.IAsyncResult asyncResult) [0x000af] in <bd46d4d4f7964dfa9beea098499ab597>:0 at System.IO.Stream.<BeginEndWriteAsync>m__8 (System.IO.Stream stream, System.IAsyncResult asyncResult) [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at (wrapper delegate-invoke) System.Func`3[System.IO.Stream,System.IAsyncResult,System.Threading.Tasks.VoidTaskResult]:invoke_TResult_T1_T2 (System.IO.Stream,System.IAsyncResult) at System.Threading.Tasks.TaskFactory`1+FromAsyncTrimPromise`1[TResult,TInstance].Complete (TInstance thisRef, System.Func`3[T1,T2,TResult] endMethod, System.IAsyncResult asyncResult, System.Boolean requiresSynchronization) [0x00002] in <8f2c484307284b51944a1a13a14c0266>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at System.IO.Stream+<CopyToAsyncInternal>c__async0.MoveNext () [0x0009b] in <8f2c484307284b51944a1a13a14c0266>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at SocketHttpListener.Net.ResponseStream+<TransmitFileManaged>c__async2.MoveNext () [0x00215] in <be8b138bd9e244198adda21dfe43a054>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.HttpServer.FileWriter+<WriteToAsync>c__async0.MoveNext () [0x0012c] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.Services.ResponseHelper+<WriteToResponseInternal>c__async0.MoveNext () [0x003c7] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.Services.ServiceHandler+<ProcessRequestAsync>c__async1.MoveNext () [0x002a6] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 --- End of stack trace from previous location where exception was thrown --- at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <8f2c484307284b51944a1a13a14c0266>:0 at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () [0x00000] in <8f2c484307284b51944a1a13a14c0266>:0 at Emby.Server.Implementations.HttpServer.HttpListenerHost+<RequestHandler>c__async0.MoveNext () [0x00674] in <46e8bbb0b1aa48e4b1435853dc7a70a2>:0 InnerException: System.Net.Sockets.SocketException System.Net.Sockets.SocketException (0x80004005): The socket has been shut down at System.Net.Sockets.Socket.EndSend (System.IAsyncResult result) [0x00033] in <bd46d4d4f7964dfa9beea098499ab597>:0 at System.Net.Sockets.NetworkStream.EndWrite (System.IAsyncResult asyncResult) [0x0005f] in <bd46d4d4f7964dfa9beea098499ab597>:0 at System.Net.Sockets.Socket.EndSend (System.IAsyncResult result) [0x00033] in <bd46d4d4f7964dfa9beea098499ab597>:0 at System.Net.Sockets.NetworkStream.EndWrite (System.IAsyncResult asyncResult) [0x0005f] in <bd46d4d4f7964dfa9beea098499ab597>:0 2017-04-29 10:44:59.1529 Info HttpServer: HTTP Response 500 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 23393ms (slow). http://emby.mydomain.com:8096/emby/videos/c7c42ded1a0fca4a18b6a9a918a7b4a7/hls1/main/0.ts?DeviceId=ff69f89d543f345b125d5209c9b69340fd509aaa&MediaSourceId=c7c42ded1a0fca4a18b6a9a918a7b4a7&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=1&VideoBitrate=33840599&AudioBitrate=384000&Level=51&Profile=high&PlaySessionId=2555c3c3e9df40c1a4c9649825b5d666&CopyTimestamps=false&TranscodingMaxAudioChannels=6&EnableSubtitlesInManifest=false&Tag=63c5d48c8c8a700d8fdc848be0bd4075&RequireAvc=true&RequireNonAnamorphic=false&DeInterlace=false&SegmentContainer=ts&MinSegments=2&BreakOnNonKeyFrames=True 2017-04-29 10:45:04.4765 Debug App: ActivityLogWebSocketListener stop transmitting over websocket to ::ffff:192.168.200.1:41506 2017-04-29 10:45:04.4765 Debug App: ActivityLogWebSocketListener Begin transmitting over websocket to ::ffff:192.168.200.1:41506 2017-04-29 10:45:04.4765 Debug App: SessionInfoWebSocketListener stop transmitting over websocket to ::ffff:192.168.200.1:41506 2017-04-29 10:45:04.4777 Debug App: ScheduledTasksWebSocketListener stop transmitting over websocket to ::ffff:192.168.200.1:41506 2017-04-29 10:45:04.4783 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/System/Logs. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36 2017-04-29 10:45:04.4820 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/System/Configuration. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36 2017-04-29 10:45:04.4841 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:7. Time: 2ms. http://emby.mydomain.com:8096/emby/System/Configuration 2017-04-29 10:45:04.4841 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:7. Time: 6ms. http://emby.mydomain.com:8096/emby/System/Logs 2017-04-29 10:45:07.1134 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/System/Logs/Log?name=server-63629020800.txt. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36 2017-04-29 10:45:07.8542 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:7. Time: 741ms. http://emby.mydomain.com:8096/emby/System/Logs/Log?name=server-63629020800.txt 2017-04-29 10:45:15.6401 Info App: Transcoding kill timer stopped for JobId 265db4c158f8410ebd36958de4d00177 PlaySessionId f06312a15ac24559a2b6ea88d00a4c4d. Killing transcoding 2017-04-29 10:45:15.6401 Debug App: KillTranscodingJob - JobId 265db4c158f8410ebd36958de4d00177 PlaySessionId f06312a15ac24559a2b6ea88d00a4c4d. Killing transcoding 2017-04-29 10:45:15.6402 Info App: Stopping ffmpeg process with q command for /mnt/milchstrasse/emby/transcoding-temp/d62f23848b5090c7ee989e1322b927f7.m3u8 2017-04-29 10:45:15.6888 Debug App: Disposing stream resources 2017-04-29 10:45:15.6888 Info App: FFMpeg exited with code 0 2017-04-29 10:45:15.6888 Info App: Deleting partial stream file(s) /mnt/milchstrasse/emby/transcoding-temp/d62f23848b5090c7ee989e1322b927f7.m3u8 2017-04-29 10:45:19.8473 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/Images/Backdrop/2?tag=6e2e0086e8144d0e3555e8388d9e4c0c&maxWidth=1440&quality=80. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_1) AppleWebKit/602.2.14 (KHTML, like Gecko) Version/10.0.1 Safari/602.2.14 2017-04-29 10:45:19.8746 Info HttpResultFactory: Transmit file /mnt/milchstrasse/emby/cache/temp/a654d1587aa8452ba6937dcf00f1753e.jpg 2017-04-29 10:45:19.8761 Info HttpServer: HTTP Response 200 to fdeb:d33f:909c:bde2:0:0:0:5. Time: 29ms. http://emby.mydomain.com:8096/emby/Items/c7c42ded1a0fca4a18b6a9a918a7b4a7/Images/Backdrop/2?tag=6e2e0086e8144d0e3555e8388d9e4c0c&maxWidth=1440&quality=80 2017-04-29 10:45:27.2607 Info HttpServer: HTTP GET http://emby.mydomain.com:8096/emby/System/Logs/Log?name=server-63629020800.txt. UserAgent: Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 Safari/537.36 The ffmpeg-log is attached. If needed, I can provide the whole log as well but its rather big. I have emby behind an apache reverse proxy, but I already tested it with direct access to emby and there I have the same error. The strange thing is: The error occurs only when the movie needs to be transcoded or with direct streaming, not with direct play. It seems that also ffmpeg already created the requested file, so I think, there isn´t any error on that side. Additionally, the connection quality shouldn´t be the problem cause the server has gigabit uplink and I also tested it with the PC directly on one switch with the server. Any intentions about this problem? Thanks, Alex Log-ffmpeg.txt Edited April 29, 2017 by alexxx
Luke 42078 Posted April 29, 2017 Posted April 29, 2017 Hi there @@alexxx, can you please attach the complete emby server log? thanks !
alexxx 2 Posted April 29, 2017 Author Posted April 29, 2017 (edited) @luke: I sent you the link via PM. Edited April 29, 2017 by alexxx
Luke 42078 Posted May 2, 2017 Posted May 2, 2017 502 is a status code you typically only see with proxies or vpn's. In the log you attached, there is not a single 502 response coming from Emby, therefore it must be coming from the proxy after it interprets the response from Emby. i don't see how you could have the same error status code 502 without the proxy. It looks to me like the connection was closed, probably by the proxy, and that's why Emby Server was no longer able to send data, thus leading to the playback error. If you have changed any settings on the proxy recently, I would try reverting them.
alexxx 2 Posted May 4, 2017 Author Posted May 4, 2017 I changed nothing in the last time at the proxy. Additionally, the error also occurs with direct access to emby without the proxy. I found this in the proxys error log: Timeout. I have set the following parameters in apache and they worked quite well until now: connectiontimeout=5 timeout=3 So, of course the proxy closes the connection but only because emby seems to not answering the requests. [Thu May 04 22:31:16.741644 2017] [proxy_http:error] [pid 89445:tid 140425692178176] (70007)The timeout specified has expired: [client 2003:...:c42f:58564] AH01102: error reading status line from remote server 192.168.200.2:8096, referer: https://emby.mydomain.com/web/videoosd.html [Thu May 04 22:31:16.741697 2017] [proxy:error] [pid 89445:tid 140425692178176] [client 2003:46:1a6f:a345:7c3f:6426:769:c42f:58564] AH00898: Error reading from remote server returned by /emby/videos/97126fc11c9fc83cff21a66238be60b1/hls1/main/108.ts, referer: https://emby.mydomain.com/web/videoosd.html But as said, the point is, that the error also occurs without proxy.
Luke 42078 Posted May 5, 2017 Posted May 5, 2017 That's a pretty aggressive timeout value so i would suggest increasing that.
Recommended Posts
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 accountSign in
Already have an account? Sign in here.
Sign In Now