Page 1 of 1

Few questions on the FFmpeg execution

PostPosted: Mon Jul 23, 2012 8:57 pm
by th0rax
When attempting to start trans coding a movie on my server I'm getting a error in the log as follows:

  Code:
2012-07-23 16:07:35,434 DEBUG [WebServer] Incoming connection from /192.168.1.126:60271
2012-07-23 16:07:35,446 DEBUG [ResourceTransportRequestHandler] GET /resource/193/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO*0 HTTP/1.1, headers = [Host: 192.168.1.102:8895,User-Agent: PLAYSTATION 3,Connection: Keep-Alive,Accept-Encoding: identity,transferMode.dlna.org: Streaming,X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";,TimeSeekRange.dlna.org: npt=0.000-]]
2012-07-23 16:07:35,446 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.126
2012-07-23 16:07:35,446 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.126, Profile=Playstation 3'
2012-07-23 16:07:35,447 DEBUG [ResourceTransportRequestHandler] Request for resource 193 and type 'MEDIA_ITEM' received
2012-07-23 16:07:35,447 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 193 (local)
2012-07-23 16:07:35,447 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 193)
2012-07-23 16:07:35,447 DEBUG [VideoDAOImpl] Reading a Video (id = 193)
2012-07-23 16:07:35,447 DEBUG [VideoDeliveryEngine] Delivering item '193' for client 'IPAddress=/192.168.1.126, Profile=Playstation 3'
2012-07-23 16:07:35,447 DEBUG [VideoDeliveryEngine] Delivering file 'Crouching.Tiger.Hidden.Dragon.2000.1080p.BluRay.x264.mkv' using transcoding
2012-07-23 16:07:35,447 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.126, Profile=Playstation 3'
2012-07-23 16:07:35,447 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: FFmpeg execution failed
java.io.IOException: FFmpeg execution failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:47)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:129)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:96)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:121)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:116)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:54)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:187)
2012-07-23 16:07:35,458 DEBUG [WebServer] Incoming connection from /192.168.1.126:60270
2012-07-23 16:07:35,458 DEBUG [ResourceTransportRequestHandler] GET /resource/193/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO*0 HTTP/1.1, headers = [Host: 192.168.1.102:8895,User-Agent: PLAYSTATION 3,Connection: Keep-Alive,Accept-Encoding: identity,transferMode.dlna.org: Streaming,X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";,TimeSeekRange.dlna.org: npt=0.000-]]
2012-07-23 16:07:35,458 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.126
2012-07-23 16:07:35,458 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.126, Profile=Playstation 3'
2012-07-23 16:07:35,459 DEBUG [ResourceTransportRequestHandler] Request for resource 193 and type 'MEDIA_ITEM' received
2012-07-23 16:07:35,459 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 193 (local)
2012-07-23 16:07:35,459 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 193)
2012-07-23 16:07:35,459 DEBUG [VideoDAOImpl] Reading a Video (id = 193)
2012-07-23 16:07:35,459 DEBUG [VideoDeliveryEngine] Delivering item '193' for client 'IPAddress=/192.168.1.126, Profile=Playstation 3'
2012-07-23 16:07:35,459 DEBUG [VideoDeliveryEngine] Delivering file 'Crouching.Tiger.Hidden.Dragon.2000.1080p.BluRay.x264.mkv' using transcoding
2012-07-23 16:07:35,459 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.126, Profile=Playstation 3'
2012-07-23 16:07:35,460 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: FFmpeg execution failed
java.io.IOException: FFmpeg execution failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:47)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:129)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:96)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:121)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:116)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:54)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:187)


This was recently right after I restarted the serviio script, it seems like there is a program being ran that is trying to hook to that running process of ffmpeg (which may not be available), I already have ffmpeg installed so why is there one running already for no particular reason? Is there a way I can prevent it from trying to use the built in ffmpeg executable and just use the one that's within my system's PATH? Or, is this even what it is trying to do? If it's trying to hook to the running process what is the command it is running to do so? I'm running a stripped down version of linux on my NAS, it's a Thecus N5500, so some programs may not be available.

Re: Few questions on the FFmpeg execution

PostPosted: Wed Jul 25, 2012 12:35 am
by th0rax
Anyone..? I'm currently using ps3mediaserver on my NAS but would like to replace it with this if I can get it running..

Re: Few questions on the FFmpeg execution

PostPosted: Wed Jul 25, 2012 1:08 am
by th0rax
Here is the output from Serviio 1.0.0. The user running the script has access to the tmp directory mentioned in the error:

  Code:
2012-07-24 21:02:06,259 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.127, Profile=Playstation 3'
2012-07-24 21:02:06,259 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/raid0/data/serviio-1.0/tmp/Serviio/transcoding-temp-6123-4-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/raid0/data/serviio-1.0/tmp/Serviio/transcoding-temp-6123-4-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:45)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:125)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:95)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:120)
   at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:84)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:79)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:191)
2012-07-24 21:02:06,268 DEBUG [WebServer] Incoming connection from /192.168.1.127:61740
2012-07-24 21:02:06,268 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.127
2012-07-24 21:02:06,269 DEBUG [ResourceTransportRequestHandler] GET /resource/6123/MEDIA_ITEM/AVC_TS_MP_HD_AC3_ISO-0/ORIGINAL HTTP/1.1, headers = [Host: 192.168.1.102:8895,User-Agent: PLAYSTATION 3,Connection: Keep-Alive,Accept-Encoding: identity,transferMode.dlna.org: Streaming,X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";,TimeSeekRange.dlna.org: npt=0.000-]]
2012-07-24 21:02:06,269 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.127
2012-07-24 21:02:06,269 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.127, Profile=Playstation 3'
2012-07-24 21:02:06,269 DEBUG [ResourceDeliveryProcessor] Request for resource 6123 and type 'MEDIA_ITEM' received
2012-07-24 21:02:06,269 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 6123 (local)
2012-07-24 21:02:06,269 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 6123)
2012-07-24 21:02:06,269 DEBUG [VideoDAOImpl] Reading a Video (id = 6123)
2012-07-24 21:02:06,270 DEBUG [VideoDeliveryEngine] Delivering item '6123' for client 'IPAddress=/192.168.1.127, Profile=Playstation 3'
2012-07-24 21:02:06,270 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.127, Profile=Playstation 3'
2012-07-24 21:02:06,270 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/raid0/data/serviio-1.0/tmp/Serviio/transcoding-temp-6123-4-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/raid0/data/serviio-1.0/tmp/Serviio/transcoding-temp-6123-4-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:45)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:125)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:95)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:120)
   at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:84)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:79)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:191)


It for some reason thinks it's already running when it's not, there is no ffmpeg process running but tries to attach to one

Re: Few questions on the FFmpeg execution

PostPosted: Wed Jul 25, 2012 9:41 pm
by zip
further up in the log you should see the ffmpeg command. run that manually and post the error message

Re: Few questions on the FFmpeg execution

PostPosted: Fri Jul 27, 2012 2:23 am
by th0rax
No, it doesn't, it is only logs the error not the command ran, I have the debug level turned on for the org.serviio log level, but it's not providing much output around the command, is there a different log level I can turn up to increase the output of the transcoder?

Re: Few questions on the FFmpeg execution

PostPosted: Fri Jul 27, 2012 12:54 pm
by zip
did you restart serviio in the meantime? if not, please do so (so that it loses list of previously transcoded files and doesn't try to re-use it). then you should definitely see the ffmpeg command.