ffmpeg Invalid argument

[alexwilson 3]

Playback/resume is failing, the transcode logs show this:

http://xxx:8096/emby/videos/9caa238929e18f9c8bc9c4239ae54917/hls1/main/0.ts?DeviceId=1B899615-5566-4FFA-A04F-C6A5872D5F5A&MediaSourceId=9caa238929e18f9c8bc9c4239ae54917&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=2&VideoBitrate=808000&AudioBitrate=192000&MaxAudioChannels=6&Level=41&Profile=high&PlaySessionId=2d44b9868361408180a844959060ade6&api_key=08eed9014b994419b28dd257bfb70b16&CopyTimestamps=false&EnableSubtitlesInManifest=false&Tag=6a40fa216c7c61fc37f332e763d0b9ed

{"Protocol":"File","Id":"9caa238929e18f9c8bc9c4239ae54917","Path":"\\\\nas1\\shared\\LizzieTV\\Teen.Titans\\Teen.Titans.s02e12.Aftershock_[2of2].mkv","Type":"Default","Container":"mkv","Name":"480P/H264/AC3","ETag":"6a40fa216c7c61fc37f332e763d0b9ed","RunTimeTicks":13193820000,"ReadAtNativeFramerate":false,"SupportsTranscoding":true,"SupportsDirectStream":true,"SupportsDirectPlay":true,"RequiresOpening":false,"RequiresClosing":false,"VideoType":"VideoFile","MediaStreams":[{"Codec":"h264","Language":"eng","TimeBase":"1/1000","CodecTimeBase":"1001/48000","NalLengthSize":"4","IsInterlaced":false,"IsAVC":true,"BitRate":1767635,"BitDepth":8,"RefFrames":6,"IsDefault":true,"IsForced":false,"Height":480,"Width":704,"AverageFrameRate":23.97602,"RealFrameRate":23.97602,"Profile":"High","Type":"Video","AspectRatio":"4:3","Index":0,"IsExternal":false,"IsTextSubtitleStream":false,"SupportsExternalStream":false,"PixelFormat":"yuv420p","Level":30,"IsAnamorphic":false},{"Codec":"ac3","Language":"rus","TimeBase":"1/1000","CodecTimeBase":"1/48000","Title":"ZM","DisplayTitle":"ZM","IsInterlaced":false,"ChannelLayout":"stereo","BitRate":192000,"Channels":2,"SampleRate":48000,"IsDefault":true,"IsForced":false,"Type":"Audio","Index":1,"IsExternal":false,"IsTextSubtitleStream":false,"SupportsExternalStream":false,"Level":0},{"Codec":"aac","Language":"eng","TimeBase":"1/1000","CodecTimeBase":"1/48000","DisplayTitle":"Eng AAC stereo","IsInterlaced":false,"ChannelLayout":"stereo","Channels":2,"SampleRate":48000,"IsDefault":false,"IsForced":false,"Profile":"HE-AAC","Type":"Audio","Index":2,"IsExternal":false,"IsTextSubtitleStream":false,"SupportsExternalStream":false,"Level":0},{"Codec":"srt","Language":"rus","TimeBase":"1/1000","CodecTimeBase":"1/1000","Title":"Mr. Anonim","DisplayTitle":"Mr. Anonim","IsInterlaced":false,"IsDefault":true,"IsForced":false,"Type":"Subtitle","Index":3,"IsExternal":false,"IsTextSubtitleStream":true,"SupportsExternalStream":true,"Level":0}],"PlayableStreamFileNames":[],"Formats":[],"Bitrate":1767635,"RequiredHttpHeaders":{}}

C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\ffmpeg\20160131\ffmpeg.exe -i file:"\\nas1\shared\LizzieTV\Teen.Titans\Teen.Titans.s02e12.Aftershock_[2of2].mkv" -map_metadata -1 -threads 0 -map 0:0 -map 0:2 -map -0:s -codec:v:0 libx264 -pix_fmt yuv420p -preset superfast -crf 23 -b:v 808000 -maxrate 808000 -bufsize 1616000 -vsync -1 -profile:v high -level 41 -force_key_frames "expr:gte(t,n_forced*3)" -vf "scale=trunc(min(max(iw\,ih*dar)\,720)/2)*2:trunc(ow/dar/2)*2" -flags -global_header -copyts -codec:a:0 aac -strict experimental -ac 2 -ab 192000 -af "adelay=1,aresample=async=1" -max_delay 5000000 -avoid_negative_ts disabled -start_at_zero -hls_time 3 -start_number 0 -hls_list_size 0 -y "R:\transcoding-temp\14cb92f319c451fb9cfb75a7d7502f4a.m3u8"


ffmpeg version N-78218-gc86ecdf Copyright (c) 2000-2016 the FFmpeg developers
  built with gcc 5.3.0 (GCC)
  configuration: --arch=x86_64 --target-os=mingw32 --cross-prefix=/home/mceuser/ffmpeg-windows-build-helpers/sandbox/cross_compilers/mingw-w64-x86_64/bin/x86_64-w64-mingw32- --pkg-config=pkg-config --disable-w32threads --enable-gpl --enable-libsoxr --enable-fontconfig --enable-libass --enable-libutvideo --enable-libbluray --enable-iconv --enable-libtwolame --extra-cflags=-DLIBTWOLAME_STATIC --enable-libzvbi --enable-libcaca --enable-libmodplug --extra-libs=-lstdc++ --extra-libs=-lpng --enable-libvidstab --enable-libx265 --enable-decklink --extra-libs=-loleaut32 --enable-libx264 --enable-libxvid --enable-libmp3lame --enable-version3 --enable-zlib --enable-librtmp --enable-libvorbis --enable-libtheora --enable-libspeex --enable-libopenjpeg --enable-gnutls --enable-libgsm --enable-libfreetype --enable-libopus --enable-frei0r --enable-filter=frei0r --enable-bzlib --enable-libxavs --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libvo-amrwbenc --enable-libschroedinger --enable-libvpx --enable-libilbc --enable-libwavpack --enable-libwebp --enable-libgme --enable-dxva2 --enable-libdcadec --enable-avisynth --enable-gray --enable-libopenh264 --enable-libmfx --extra-libs=-lpsapi --extra-cflags= --enable-static --disable-shared --prefix=/home/mceuser/ffmpeg-windows-build-helpers/sandbox/cross_compilers/mingw-w64-x86_64/x86_64-w64-mingw32 --enable-runtime-cpudetect
  libavutil      55. 16.101 / 55. 16.101
  libavcodec     57. 24.100 / 57. 24.100
  libavformat    57. 23.101 / 57. 23.101
  libavdevice    57.  0.101 / 57.  0.101
  libavfilter     6. 27.100 /  6. 27.100
  libswscale      4.  0.100 /  4.  0.100
  libswresample   2.  0.101 /  2.  0.101
  libpostproc    54.  0.100 / 54.  0.100
file:\\nas1\shared\LizzieTV\Teen.Titans\Teen.Titans.s02e12.Aftershock_[2of2].mkv: Invalid argument

Server log banner:

2016-09-08 00:00:00.0324 Info App: Application version: 3.0.6400.0
2016-09-08 00:00:00.0324 Info App: Emby
	Command line: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	Processor count: 4
	64-Bit OS: True
	64-Bit Process: True
	Program data path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server
	Application Path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe

This also shows up in the server log, not sure if related (there seem to be metadata error for pretty much everything):

2016-09-08 07:03:47.8203 Debug ProviderManager: Saving \\nas1\shared\LizzieTV\Teen.Titans\Teen.Titans.s02e12.Aftershock_[1of2].mkv to Nfo.
2016-09-08 07:03:51.3106 Error ProviderManager: Error in metadata saver
	*** Error Report ***
	Version: 3.0.6400.0
	Command line: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	Processor count: 4
	64-Bit OS: True
	64-Bit Process: True
	Program data path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server
	Application Path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe
	The specified server cannot perform the requested operation.
	
	System.IO.IOException
	   at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath)
	   at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
	   at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
	   at System.IO.StreamReader..ctor(String path, Encoding encoding, Boolean detectEncodingFromByteOrderMarks, Int32 bufferSize, Boolean checkHost)
	   at System.IO.StreamReader..ctor(String path, Encoding encoding)
	   at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.AddCustomTags(String path, List`1 xmlTagsUsed, XmlWriter writer, ILogger logger)
	   at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(IHasMetadata item, Stream stream, String xmlPath)
	   at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(IHasMetadata item, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Manager.ProviderManager.<SaveMetadata>d__60.MoveNext()
	

More from the server log that looks like it's from the playback attempt:

2016-09-08 07:45:46.1151 Info HttpServer: HTTP GET http://xxx:8096/emby/videos/9caa238929e18f9c8bc9c4239ae54917/hls1/main/0.ts?DeviceId=1B899615-5566-4FFA-A04F-C6A5872D5F5A&MediaSourceId=9caa238929e18f9c8bc9c4239ae54917&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=2&VideoBitrate=808000&AudioBitrate=192000&MaxAudioChannels=6&Level=41&Profile=high&PlaySessionId=adfeb2150a7e4e179be7396a6d019ede&CopyTimestamps=false&EnableSubtitlesInManifest=false&Tag=6a40fa216c7c61fc37f332e763d0b9ed. UserAgent: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36
2016-09-08 07:45:46.1307 Debug App: Starting transcoding because currentTranscodingIndex=null
2016-09-08 07:45:46.1307 Debug App: KillTranscodingJob - JobId e06aac1320c44a1aba22d98fa9901ea3 PlaySessionId adfeb2150a7e4e179be7396a6d019ede. Killing transcoding
2016-09-08 07:45:46.1307 Info App: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\ffmpeg\20160131\ffmpeg.exe -i file:"\\nas1\shared\LizzieTV\Teen.Titans\Teen.Titans.s02e12.Aftershock_[2of2].mkv" -map_metadata -1 -threads 0 -map 0:0 -map 0:2 -map -0:s -codec:v:0 libx264 -pix_fmt yuv420p -preset superfast -crf 23 -b:v 808000 -maxrate 808000 -bufsize 1616000 -vsync -1 -profile:v high -level 41 -force_key_frames "expr:gte(t,n_forced*3)" -vf "scale=trunc(min(max(iw\,ih*dar)\,720)/2)*2:trunc(ow/dar/2)*2" -flags -global_header -copyts -codec:a:0 aac -strict experimental -ac 2 -ab 192000 -af "adelay=1,aresample=async=1" -max_delay 5000000 -avoid_negative_ts disabled -start_at_zero -hls_time 3 -start_number 0 -hls_list_size 0 -y "R:\transcoding-temp\9a64e6c39206a68576bf641fa40f108b.m3u8"
2016-09-08 07:45:46.1307 Error ServiceStackHost: Error occured while Processing Request: A task was canceled.
	*** Error Report ***
	Version: 3.0.6400.0
	Command line: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	Processor count: 4
	64-Bit OS: True
	64-Bit Process: True
	Program data path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server
	Application Path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe
	A task was canceled.
	System.Threading.Tasks.TaskCanceledException
	   at ServiceStack.TaskExt.GetResult(Task task)
	   at ServiceStack.Host.ServiceRunner`1.<>c__DisplayClass12_1.<Execute>b__0(Task task)
	   at System.Threading.Tasks.ContinuationResultTaskFromTask`1.InnerInvoke()
	   at System.Threading.Tasks.Task.Execute()
	--- 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 ServiceStack.Host.Handlers.ServiceStackHandlerBase.<HandleResponseAsync>d__13.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 ServiceStack.Host.RestHandler.<ProcessRequestAsync>d__13.MoveNext()
	
2016-09-08 07:45:46.1307 Info HttpServer: HTTP Response 500 to 198.84.193.11. Time: 10136ms (slow). http://xxx:8096/emby/videos/9caa238929e18f9c8bc9c4239ae54917/hls1/main/0.ts?DeviceId=1B899615-5566-4FFA-A04F-C6A5872D5F5A&MediaSourceId=9caa238929e18f9c8bc9c4239ae54917&VideoCodec=h264&AudioCodec=aac,mp3&AudioStreamIndex=2&VideoBitrate=808000&AudioBitrate=192000&MaxAudioChannels=6&Level=41&Profile=high&PlaySessionId=adfeb2150a7e4e179be7396a6d019ede&CopyTimestamps=false&EnableSubtitlesInManifest=false&Tag=6a40fa216c7c61fc37f332e763d0b9ed
2016-09-08 07:45:46.3535 Error ProviderManager: Error in metadata saver
	*** Error Report ***
	Version: 3.0.6400.0
	Command line: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe -service
	Operating system: Microsoft Windows NT 6.2.9200.0
	Processor count: 4
	64-Bit OS: True
	64-Bit Process: True
	Program data path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server
	Application Path: C:\Users\Alex\AppData\Roaming\MediaBrowser-Server\System\MediaBrowser.ServerApplication.exe
	The specified server cannot perform the requested operation.
	
	System.IO.IOException
	   at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath)
	   at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
	   at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options, String msgPath, Boolean bFromProxy, Boolean useLongPath, Boolean checkHost)
	   at System.IO.StreamReader..ctor(String path, Encoding encoding, Boolean detectEncodingFromByteOrderMarks, Int32 bufferSize, Boolean checkHost)
	   at System.IO.StreamReader..ctor(String path, Encoding encoding)
	   at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.AddCustomTags(String path, List`1 xmlTagsUsed, XmlWriter writer, ILogger logger)
	   at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(IHasMetadata item, Stream stream, String xmlPath)
	   at MediaBrowser.XbmcMetadata.Savers.BaseNfoSaver.Save(IHasMetadata item, CancellationToken cancellationToken)
	   at MediaBrowser.Providers.Manager.ProviderManager.<SaveMetadata>d__60.MoveNext()

[Luke 37325]

This looks like an issue with your file system or storage device. We are trying to save a metadata file and the operating system is throwing an I/O device error:

The specified server cannot perform the requested operation.
	
	System.IO.IOException

Additionally it looks like ffmpeg is having trouble accessing the path as well.

[alexwilson 3]

Thanks, you were right.  It looks like the service lost network permissions to my NAS (which I'm pretty sure is going to be Windows' fault).  Running the server process from the desktop account resolved the issue.

 

The recent Windows 10 update this week deleted the Emby service (yet again), not sure why the network permissions changed, but looks like they did.

[Luke 37325]

I noticed that too with Windows 10. Thanks for confirming.