Media refresh failed, ffprobe error

[fRzzy 0]

I have Emby server running as a Docker container, OS is LibreELEC 8.1.2 (beta), Emby server is up to date Version 3.2.33.0)

 

Radarr just downloaded a new version for existing media in the library and notify Emby about that, these are related log lines:

2017-10-03 09:10:42.515 Info HttpServer: HTTP POST http://192.168.1.138:8096/mediabrowser/Library/Movies/Updated?ImdbId=tt2250912. UserAgent: Radarr/0.2.0.852 (Linux 4.11)
2017-10-03 09:10:43.561 Info HttpServer: HTTP Response 204 to 172.18.0.2. Time: 1053ms (slow). http://192.168.1.138:8096/mediabrowser/Library/Movies/Updated?ImdbId=tt2250912 
2017-10-03 09:11:43.641 Info LibraryMonitor: Spider-Man: Homecoming (/data/movies/Spider-Man Homecoming (2017)/Spider-Man Homecoming (2017).mkv) will be refreshed.
2017-10-03 09:11:44.038 Error App: Error in ffprobe
	*** Error Report ***
	Version: 3.2.33.0
	Command line: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe -programdata /config -restartpath /usr/lib/emby-server/restart.sh
	Operating system: Unix 4.11.12.0
	64-Bit OS: True
	64-Bit Process: True
	User Interactive: False
	Mono: 4.8.1 (Stable 4.8.1.0/22a39d7 Sun Oct  1 17:55:43 UTC 2017)
	Processor count: 4
	Program data path: /config
	Application directory: /usr/lib/emby-server/bin
	System.ComponentModel.Win32Exception (0x80004005): ApplicationName='/config/ffmpeg/20170308/ffprobe', CommandLine='-i file:"/data/movies/Spider-Man Homecoming (2017)/Spider-Man Homecoming (2017).mkv" -threads 0 -v info -print_format json -show_streams -show_chapters -show_format', CurrentDirectory='', Native error= Out of memory
	  at System.Diagnostics.Process.StartWithCreateProcess (System.Diagnostics.ProcessStartInfo startInfo) [0x001ee] in <5641e4edad4f4464ba58c620a7b8ea48>:0 
	--- End of stack trace from previous location where exception was thrown ---
	  at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at MediaBrowser.Providers.MediaInfo.FFProbeVideoInfo+<ProbeVideo>d__15`1[T].MoveNext () [0x002e9] in <91ccd64e3d9d47ce835b55d41292c7fd>:0 
	--- End of stack trace from previous location where exception was thrown ---
	  at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at MediaBrowser.Providers.Manager.MetadataService`2+<RunCustomProvider>d__24[TItemType,TIdType].MoveNext () [0x000d7] in <91ccd64e3d9d47ce835b55d41292c7fd>:0 
	System.ComponentModel.Win32Exception
	  at System.Diagnostics.Process.StartWithCreateProcess (System.Diagnostics.ProcessStartInfo startInfo) [0x001ee] in <5641e4edad4f4464ba58c620a7b8ea48>:0 
	--- End of stack trace from previous location where exception was thrown ---
	  at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at MediaBrowser.Providers.MediaInfo.FFProbeVideoInfo+<ProbeVideo>d__15`1[T].MoveNext () [0x002e9] in <91ccd64e3d9d47ce835b55d41292c7fd>:0 
	--- End of stack trace from previous location where exception was thrown ---
	  at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at MediaBrowser.Providers.Manager.MetadataService`2+<RunCustomProvider>d__24[TItemType,TIdType].MoveNext () [0x000d7] in <91ccd64e3d9d47ce835b55d41292c7fd>:0 
	
2017-10-03 09:11:49.182 Info App: Emby.Kodi.SyncQueue: Starting Library Sync...
2017-10-03 09:11:49.182 Info App: Emby.Kodi.SyncQueue: "LIBRARYSYNC" Added 0 items:  
2017-10-03 09:11:49.222 Info App: Emby.Kodi.SyncQueue: "LIBRARYSYNC" Updated 1 items:  9f26f45e2a5cbdf22e136b45bebd91a6
2017-10-03 09:11:49.222 Info App: Emby.Kodi.SyncQueue: "LIBRARYSYNC" Removed 0 items:  
2017-10-03 09:11:49.222 Info App: Emby.Kodi.SyncQueue: Finished Library Sync Taking 00:00:00.0404540

New media codec info wasn't updated, I have to force analyze by manually refresh metadata for that item.

[Luke 42078]

Can you please attach the complete emby server log? thanks !

[fRzzy 0]

Here it is, I hope it's useful.

 

Note that there are 2-3 more updated files after that, all triggered by Sonarr/Radarr and none of them encountered this error.

ffprobe error.log.txt.zip

[Luke 42078]

It is unable to start ffprobe. You need to go here:

/config/ffmpeg/20170308

and make sure that both the ffmpeg and ffprobe files are marked with execute privileges so that the server will be able to execute them.

 

we're working on improvements to the docker container and soon you'll never have to worry about this again. Thanks.

[fRzzy 0]

both of them are marked as executable to me, note that I'm using linuxserver.io's emby add-on for LibreELEC but the image is emby/embyserver:latest so it's untouched.

LibreELEC:~ # ls -la .kodi/userdata/addon_data/docker.linuxserver.emby/ffmpeg/20170308/
total 89138
drwxrwxr-x    2 nobody   users         1024 Oct  2 06:42 .
drwxrwxr-x    3 nobody   users         1024 Oct  2 06:42 ..
-rwxrwxrwx    1 nobody   users     45680536 Oct  2 06:42 ffmpeg
-rwxrwxrwx    1 nobody   users     45594200 Oct  2 06:42 ffprobe

LibreELEC:~ # docker exec -it 71ab35cacd6b /bin/bash
bash-4.4# ls -la /config/ffmpeg/20170308/
total 89138
drwxrwxr-x 2 nobody nobody     1024 Oct  2 06:42 .
drwxrwxr-x 3 nobody nobody     1024 Oct  2 06:42 ..
-rwxrwxrwx 1 nobody nobody 45680536 Oct  2 06:42 ffmpeg
-rwxrwxrwx 1 nobody nobody 45594200 Oct  2 06:42 ffprobe

Edited October 3, 2017 by fRzzy