Serviio - DirecTV: ffmpeg transcription error

Searched the forums, etc. and can't seem to find the same scenario.
1. DTV receiver(s) see listings from serviio and I'm able to browse the listings.
2. Seems to start loading movie once I select a movie to play.
3. DTV kicks back to listing screen after a while.
A review of serviio logs (edited version below and hopefully I left in enough), shows that "Process ffmpeg has a return code of 1! This is a possible error."
Is it a permissions problem with the tmp directory? I seem to have enough space. Also, if I run ffmpeg from CLI (see below captured telnet screen), it doesn't give me errors--although I'm not saving the out to the tmp directory.
Anyone have any idea of what I can try next? Took some time to set-up serviio, and I was excited to see the listing...then hit this wall.
Summary of my system info:
Ubuntu 12.04 (64-bit) with all updates
Serviio 1.0.1
Java 1.7.0_07-Oracle Corporation
ffmpeg version 0.8.3-4:0.8.3-0ubuntu0.12.04.1 (it was already installed before--either from xbmc or plex, but I've removed these a while ago).
Telnet Capture
-------------------------------------------------------
ffmpeg -i "/Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv" ~/test.mkv
ffmpeg version 0.8.3-4:0.8.3-0ubuntu0.12.04.1, Copyright (c) 2000-2012 the Libav developers
built on Jun 12 2012 16:52:09 with gcc 4.6.3
*** THIS PROGRAM IS DEPRECATED ***
This program is only provided for compatibility and will be removed in a future release. Please use avconv instead.
[matroska,webm @ 0x14279a0] Estimating duration from bitrate, this may be inaccurate
Seems stream 1 codec frame rate differs from container frame rate: 47.95 (5000000/104271) -> 23.98 (24000/1001)
Input #0, matroska,webm, from '/Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv':
Duration: 01:42:29.44, start: 0.000000, bitrate: 640 kb/s
Stream #0.0: Audio: ac3, 48000 Hz, 5.1, s16, 640 kb/s (default)
Stream #0.1(eng): Video: h264 (High), yuv420p, 1920x800, PAR 1:1 DAR 12:5, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
[buffer @ 0x1498060] w:1920 h:800 pixfmt:yuv420p
[libx264 @ 0x142ade0] using SAR=1/1
[libx264 @ 0x142ade0] using cpu capabilities: MMX2 SSE2Slow SlowCTZ
[libx264 @ 0x142ade0] profile Main, level 4.0
[libx264 @ 0x142ade0] 264 - core 120 r2151 a3f4407 - H.264/MPEG-4 AVC codec - Copyleft 2003-2011 - http://www.videolan.org/x264.html - options: cabac=1 ref=3 deblock=1:0:0 analyse=0x1:0x111 me=hex subme=7 psy=1 psy_rd=1.00:0.00 mixed_ref=0 me_range=16 chroma_me=1 trellis=1 8x8dct=0 cqm=0 deadzone=21,11 fast_pskip=1 chroma_qp_offset=-2 threads=1 sliced_threads=0 nr=0 decimate=1 interlaced=0 bluray_compat=0 constrained_intra=0 bframes=3 b_pyramid=0 b_adapt=1 b_bias=0 direct=1 weightb=0 open_gop=1 weightp=2 keyint=250 keyint_min=23 scenecut=40 intra_refresh=0 rc_lookahead=40 rc=crf mbtree=1 crf=23.0 qcomp=0.60 qpmin=0 qpmax=69 qpstep=4 ip_ratio=1.25 aq=1:1.00
Output #0, matroska, to '/home/gct/test.mkv':
Metadata:
encoder : Lavf53.21.0
Stream #0.0(eng): Video: libx264, yuv420p, 1920x800 [PAR 1:1 DAR 12:5], q=-1--1, 1k tbn, 23.98 tbc (default)
Stream #0.1: Audio: libvorbis, 48000 Hz, 5.1, s16, 200 kb/s (default)
Stream mapping:
Stream #0.1 -> #0.0
Stream #0.0 -> #0.1
Press ctrl-c to stop encoding
-----------------------------------------------------------------
end Telnet Capture
Log File (DEBUG level)
-----------------------------------------------------------------
2012-09-05 10:17:58,585 INFO [MediaServer] ------------------------------------------------------------------------
2012-09-05 10:17:58,585 INFO [MediaServer] Serviio DLNA media streaming server v 1.0.1 (rev. 6dbf7c6e334f+)
2012-09-05 10:17:58,586 INFO [MediaServer] Petr Nejedly 2009-2012
2012-09-05 10:17:58,586 INFO [MediaServer] http://www.serviio.org
2012-09-05 10:17:58,586 INFO [MediaServer]
2012-09-05 10:17:58,586 INFO [MediaServer] Java 1.7.0_07-Oracle Corporation
2012-09-05 10:17:58,586 INFO [MediaServer] OS Linux amd64 3.2.0-29-generic
2012-09-05 10:17:58,586 INFO [MediaServer] File encoding: US-ASCII
2012-09-05 10:17:58,587 INFO [MediaServer] Headless mode enabled: true
2012-09-05 10:17:58,588 INFO [MediaServer] User home dir: /root
2012-09-05 10:17:58,588 INFO [MediaServer] Temp dir: /tmp
2012-09-05 10:17:58,588 INFO [MediaServer] ------------------------------------------------------------------------
...
2012-09-05 10:18:19,260 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path ffmpeg
2012-09-05 10:18:19,279 DEBUG [ProcessExecutor] Starting ffmpeg
2012-09-05 10:18:20,758 WARN [FFMPEGWrapper] FFmpeg is not compiled with librtmp support, RTMP streaming will not work.
2012-09-05 10:18:20,759 WARN [FFMPEGWrapper] Could not work out FFmpeg default User-Agent
...
2012-09-05 10:19:42,181 DEBUG [WebServer] Incoming connection from /192.168.1.40:41486
2012-09-05 10:19:42,184 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:19:42,187 DEBUG [ResourceTransportRequestHandler] GET /resource/44/MEDIA_ITEM/MPEG_PS_PAL-0/ORIGINAL HTTP/1.1, headers = [Host: 192.168.1.10,Date: Wed, Sep 5 10:19:36 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-09-05 10:19:42,189 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:19:42,201 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:19:42,207 DEBUG [ResourceDeliveryProcessor] Request for resource 44 and type 'MEDIA_ITEM' received
2012-09-05 10:19:42,210 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 44 (local)
2012-09-05 10:19:42,210 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 44)
2012-09-05 10:19:42,213 DEBUG [VideoDAOImpl] Reading a Video (id = 44)
2012-09-05 10:19:42,217 DEBUG [VideoDeliveryEngine] Delivering item '44' for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:19:42,218 DEBUG [VideoDeliveryEngine] Delivering file '1920-dirtyharry1080.mkv' using transcoding
2012-09-05 10:19:42,218 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:19:42,220 DEBUG [MediaItemDAOImpl] Getting file of media item 44
2012-09-05 10:19:42,225 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /../Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv
2012-09-05 10:19:42,226 DEBUG [ProcessExecutor] Starting ffmpeg -i /../Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv -y -threads 1 -copyts -c:v mpeg2video -b:v 8192k -maxrate:v 8192k -bufsize:v 8192k -vf pad=1920:1080:0:140:black,setdar=4:3 -r 24000/1001 -g 15 -c:a mp2 -b:a 192k -ac 2 -map 0:1 -map 0:0 -sn -f vob /tmp/Serviio/transcoding-temp-44-6-ORIGINAL.stf
2012-09-05 10:19:42,650 WARN [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2012-09-05 10:19:42,650 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
...
2012-09-05 10:19:45,336 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.1.10, timeout = 0
...
2012-09-05 10:19:49,730 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/tmp/Serviio/transcoding-temp-44-6-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/tmp/Serviio/transcoding-temp-44-6-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-09-05 10:19:52,555 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target uuid:DIRECTV2PC-Media-Server1_0-RID-028915783907 from address /192.168.1.40:49153
2012-09-05 10:19:52,556 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
...
2012-09-05 10:20:12,224 DEBUG [WebServer] Incoming connection from /192.168.1.40:41492
2012-09-05 10:20:12,225 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:20:12,228 DEBUG [ResourceTransportRequestHandler] GET /resource/46/MEDIA_ITEM/MPEG_PS_PAL-0/ORIGINAL HTTP/1.1, headers = [Host: 192.168.1.10,Date: Wed, Sep 5 10:20:06 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-09-05 10:20:12,228 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:20:12,231 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:20:12,234 DEBUG [ResourceDeliveryProcessor] Request for resource 46 and type 'MEDIA_ITEM' received
2012-09-05 10:20:12,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 46 (local)
2012-09-05 10:20:12,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 46)
2012-09-05 10:20:12,237 DEBUG [VideoDAOImpl] Reading a Video (id = 46)
2012-09-05 10:20:12,241 DEBUG [VideoDeliveryEngine] Delivering item '46' for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:20:12,242 DEBUG [VideoDeliveryEngine] Delivering file 'Memento.2000.mkv' using transcoding
2012-09-05 10:20:12,242 DEBUG [AbstractTranscodingDeliveryEngine] No other client uses transcoding job of file 'transcoding-temp-44-6-ORIGINAL.stf', will stop the job
2012-09-05 10:20:12,243 DEBUG [AbstractTranscodingDeliveryEngine] Stopping previous transcoding job of file 'transcoding-temp-44-6-ORIGINAL.stf'
2012-09-05 10:20:12,243 DEBUG [TranscodingJobListener] Deleted temp file '/tmp/Serviio/transcoding-temp-44-6-ORIGINAL.stf': false
2012-09-05 10:20:12,244 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:20:12,244 DEBUG [MediaItemDAOImpl] Getting file of media item 46
2012-09-05 10:20:12,248 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /../Public/sabnzbd/Memento 2000/Memento.2000.mkv
2012-09-05 10:20:12,249 DEBUG [ProcessExecutor] Starting ffmpeg -i /../Public/sabnzbd/Memento 2000 /Memento.2000.mkv -y -threads 1 -copyts -c:v mpeg2video -b:v 8192k -maxrate:v 8192k -bufsize:v 8192k -vf pad=1920:1080:0:132:black,setdar=4:3 -r 24000/1001 -g 15 -c:a mp2 -b:a 192k -ac 2 -map 0:0 -map 0:1 -sn -f vob /tmp/Serviio/transcoding-temp-46-6-ORIGINAL.stf
2012-09-05 10:20:12,584 WARN [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2012-09-05 10:20:12,584 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
...
2012-09-05 10:20:17,861 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target uuid:DIRECTV2PC-Media-Server1_0-RID-028915783907 from address /192.168.1.40:49153
2012-09-05 10:20:17,862 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:20:19,751 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/tmp/Serviio/transcoding-temp-46-6-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/tmp/Serviio/transcoding-temp-46-6-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)
...
------------------------------------
end Log File
1. DTV receiver(s) see listings from serviio and I'm able to browse the listings.
2. Seems to start loading movie once I select a movie to play.
3. DTV kicks back to listing screen after a while.
A review of serviio logs (edited version below and hopefully I left in enough), shows that "Process ffmpeg has a return code of 1! This is a possible error."
Is it a permissions problem with the tmp directory? I seem to have enough space. Also, if I run ffmpeg from CLI (see below captured telnet screen), it doesn't give me errors--although I'm not saving the out to the tmp directory.
Anyone have any idea of what I can try next? Took some time to set-up serviio, and I was excited to see the listing...then hit this wall.
Summary of my system info:
Ubuntu 12.04 (64-bit) with all updates
Serviio 1.0.1
Java 1.7.0_07-Oracle Corporation
ffmpeg version 0.8.3-4:0.8.3-0ubuntu0.12.04.1 (it was already installed before--either from xbmc or plex, but I've removed these a while ago).
Telnet Capture
-------------------------------------------------------
ffmpeg -i "/Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv" ~/test.mkv
ffmpeg version 0.8.3-4:0.8.3-0ubuntu0.12.04.1, Copyright (c) 2000-2012 the Libav developers
built on Jun 12 2012 16:52:09 with gcc 4.6.3
*** THIS PROGRAM IS DEPRECATED ***
This program is only provided for compatibility and will be removed in a future release. Please use avconv instead.
[matroska,webm @ 0x14279a0] Estimating duration from bitrate, this may be inaccurate
Seems stream 1 codec frame rate differs from container frame rate: 47.95 (5000000/104271) -> 23.98 (24000/1001)
Input #0, matroska,webm, from '/Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv':
Duration: 01:42:29.44, start: 0.000000, bitrate: 640 kb/s
Stream #0.0: Audio: ac3, 48000 Hz, 5.1, s16, 640 kb/s (default)
Stream #0.1(eng): Video: h264 (High), yuv420p, 1920x800, PAR 1:1 DAR 12:5, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
[buffer @ 0x1498060] w:1920 h:800 pixfmt:yuv420p
[libx264 @ 0x142ade0] using SAR=1/1
[libx264 @ 0x142ade0] using cpu capabilities: MMX2 SSE2Slow SlowCTZ
[libx264 @ 0x142ade0] profile Main, level 4.0
[libx264 @ 0x142ade0] 264 - core 120 r2151 a3f4407 - H.264/MPEG-4 AVC codec - Copyleft 2003-2011 - http://www.videolan.org/x264.html - options: cabac=1 ref=3 deblock=1:0:0 analyse=0x1:0x111 me=hex subme=7 psy=1 psy_rd=1.00:0.00 mixed_ref=0 me_range=16 chroma_me=1 trellis=1 8x8dct=0 cqm=0 deadzone=21,11 fast_pskip=1 chroma_qp_offset=-2 threads=1 sliced_threads=0 nr=0 decimate=1 interlaced=0 bluray_compat=0 constrained_intra=0 bframes=3 b_pyramid=0 b_adapt=1 b_bias=0 direct=1 weightb=0 open_gop=1 weightp=2 keyint=250 keyint_min=23 scenecut=40 intra_refresh=0 rc_lookahead=40 rc=crf mbtree=1 crf=23.0 qcomp=0.60 qpmin=0 qpmax=69 qpstep=4 ip_ratio=1.25 aq=1:1.00
Output #0, matroska, to '/home/gct/test.mkv':
Metadata:
encoder : Lavf53.21.0
Stream #0.0(eng): Video: libx264, yuv420p, 1920x800 [PAR 1:1 DAR 12:5], q=-1--1, 1k tbn, 23.98 tbc (default)
Stream #0.1: Audio: libvorbis, 48000 Hz, 5.1, s16, 200 kb/s (default)
Stream mapping:
Stream #0.1 -> #0.0
Stream #0.0 -> #0.1
Press ctrl-c to stop encoding
-----------------------------------------------------------------
end Telnet Capture
Log File (DEBUG level)
-----------------------------------------------------------------
2012-09-05 10:17:58,585 INFO [MediaServer] ------------------------------------------------------------------------
2012-09-05 10:17:58,585 INFO [MediaServer] Serviio DLNA media streaming server v 1.0.1 (rev. 6dbf7c6e334f+)
2012-09-05 10:17:58,586 INFO [MediaServer] Petr Nejedly 2009-2012
2012-09-05 10:17:58,586 INFO [MediaServer] http://www.serviio.org
2012-09-05 10:17:58,586 INFO [MediaServer]
2012-09-05 10:17:58,586 INFO [MediaServer] Java 1.7.0_07-Oracle Corporation
2012-09-05 10:17:58,586 INFO [MediaServer] OS Linux amd64 3.2.0-29-generic
2012-09-05 10:17:58,586 INFO [MediaServer] File encoding: US-ASCII
2012-09-05 10:17:58,587 INFO [MediaServer] Headless mode enabled: true
2012-09-05 10:17:58,588 INFO [MediaServer] User home dir: /root
2012-09-05 10:17:58,588 INFO [MediaServer] Temp dir: /tmp
2012-09-05 10:17:58,588 INFO [MediaServer] ------------------------------------------------------------------------
...
2012-09-05 10:18:19,260 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path ffmpeg
2012-09-05 10:18:19,279 DEBUG [ProcessExecutor] Starting ffmpeg
2012-09-05 10:18:20,758 WARN [FFMPEGWrapper] FFmpeg is not compiled with librtmp support, RTMP streaming will not work.
2012-09-05 10:18:20,759 WARN [FFMPEGWrapper] Could not work out FFmpeg default User-Agent
...
2012-09-05 10:19:42,181 DEBUG [WebServer] Incoming connection from /192.168.1.40:41486
2012-09-05 10:19:42,184 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:19:42,187 DEBUG [ResourceTransportRequestHandler] GET /resource/44/MEDIA_ITEM/MPEG_PS_PAL-0/ORIGINAL HTTP/1.1, headers = [Host: 192.168.1.10,Date: Wed, Sep 5 10:19:36 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-09-05 10:19:42,189 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:19:42,201 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:19:42,207 DEBUG [ResourceDeliveryProcessor] Request for resource 44 and type 'MEDIA_ITEM' received
2012-09-05 10:19:42,210 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 44 (local)
2012-09-05 10:19:42,210 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 44)
2012-09-05 10:19:42,213 DEBUG [VideoDAOImpl] Reading a Video (id = 44)
2012-09-05 10:19:42,217 DEBUG [VideoDeliveryEngine] Delivering item '44' for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:19:42,218 DEBUG [VideoDeliveryEngine] Delivering file '1920-dirtyharry1080.mkv' using transcoding
2012-09-05 10:19:42,218 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:19:42,220 DEBUG [MediaItemDAOImpl] Getting file of media item 44
2012-09-05 10:19:42,225 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /../Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv
2012-09-05 10:19:42,226 DEBUG [ProcessExecutor] Starting ffmpeg -i /../Public/sabnzbd/Dirty Harry/1920-dirtyharry1080.mkv -y -threads 1 -copyts -c:v mpeg2video -b:v 8192k -maxrate:v 8192k -bufsize:v 8192k -vf pad=1920:1080:0:140:black,setdar=4:3 -r 24000/1001 -g 15 -c:a mp2 -b:a 192k -ac 2 -map 0:1 -map 0:0 -sn -f vob /tmp/Serviio/transcoding-temp-44-6-ORIGINAL.stf
2012-09-05 10:19:42,650 WARN [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2012-09-05 10:19:42,650 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
...
2012-09-05 10:19:45,336 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.1.10, timeout = 0
...
2012-09-05 10:19:49,730 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/tmp/Serviio/transcoding-temp-44-6-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/tmp/Serviio/transcoding-temp-44-6-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-09-05 10:19:52,555 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target uuid:DIRECTV2PC-Media-Server1_0-RID-028915783907 from address /192.168.1.40:49153
2012-09-05 10:19:52,556 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
...
2012-09-05 10:20:12,224 DEBUG [WebServer] Incoming connection from /192.168.1.40:41492
2012-09-05 10:20:12,225 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:20:12,228 DEBUG [ResourceTransportRequestHandler] GET /resource/46/MEDIA_ITEM/MPEG_PS_PAL-0/ORIGINAL HTTP/1.1, headers = [Host: 192.168.1.10,Date: Wed, Sep 5 10:20:06 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-09-05 10:20:12,228 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:20:12,231 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:20:12,234 DEBUG [ResourceDeliveryProcessor] Request for resource 46 and type 'MEDIA_ITEM' received
2012-09-05 10:20:12,234 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 46 (local)
2012-09-05 10:20:12,234 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 46)
2012-09-05 10:20:12,237 DEBUG [VideoDAOImpl] Reading a Video (id = 46)
2012-09-05 10:20:12,241 DEBUG [VideoDeliveryEngine] Delivering item '46' for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:20:12,242 DEBUG [VideoDeliveryEngine] Delivering file 'Memento.2000.mkv' using transcoding
2012-09-05 10:20:12,242 DEBUG [AbstractTranscodingDeliveryEngine] No other client uses transcoding job of file 'transcoding-temp-44-6-ORIGINAL.stf', will stop the job
2012-09-05 10:20:12,243 DEBUG [AbstractTranscodingDeliveryEngine] Stopping previous transcoding job of file 'transcoding-temp-44-6-ORIGINAL.stf'
2012-09-05 10:20:12,243 DEBUG [TranscodingJobListener] Deleted temp file '/tmp/Serviio/transcoding-temp-44-6-ORIGINAL.stf': false
2012-09-05 10:20:12,244 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.40, Profile=DirecTV HD-DVR'
2012-09-05 10:20:12,244 DEBUG [MediaItemDAOImpl] Getting file of media item 46
2012-09-05 10:20:12,248 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /../Public/sabnzbd/Memento 2000/Memento.2000.mkv
2012-09-05 10:20:12,249 DEBUG [ProcessExecutor] Starting ffmpeg -i /../Public/sabnzbd/Memento 2000 /Memento.2000.mkv -y -threads 1 -copyts -c:v mpeg2video -b:v 8192k -maxrate:v 8192k -bufsize:v 8192k -vf pad=1920:1080:0:132:black,setdar=4:3 -r 24000/1001 -g 15 -c:a mp2 -b:a 192k -ac 2 -map 0:0 -map 0:1 -sn -f vob /tmp/Serviio/transcoding-temp-46-6-ORIGINAL.stf
2012-09-05 10:20:12,584 WARN [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2012-09-05 10:20:12,584 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
...
2012-09-05 10:20:17,861 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target uuid:DIRECTV2PC-Media-Server1_0-RID-028915783907 from address /192.168.1.40:49153
2012-09-05 10:20:17,862 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.40
2012-09-05 10:20:19,751 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/tmp/Serviio/transcoding-temp-46-6-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/tmp/Serviio/transcoding-temp-46-6-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)
...
------------------------------------
end Log File