Serviio - DirecTV: ffmpeg transcription error
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