2012-02-11 16:33:34,839 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 4583 (from=0, count=24)
2012-02-11 16:33:34,864 DEBUG [GenreDAOImpl] Reading a Genre (id = 3)
2012-02-11 16:33:34,871 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 42467)
2012-02-11 16:33:34,881 DEBUG [MediaItemDAOImpl] Getting file of media item 42467
2012-02-11 16:33:34,906 DEBUG [VideoDAOImpl] Reading a Video (id = 42467)
2012-02-11 16:33:34,917 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 42467 and profile DirecTV HD-DVR
2012-02-11 16:33:34,921 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Movie.[2011].avi: MPEG_PS_PAL
2012-02-11 16:33:34,922 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Movie.[2011].avi: MPEG_PS_NTSC
2012-02-11 16:33:34,923 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 89445)
2012-02-11 16:33:34,966 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 4583
2012-02-11 16:33:34,966 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2012-02-11 16:33:34,972 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-02-11 16:33:37,522 DEBUG [WebServer] Incoming connection from /192.168.1.149:36166
2012-02-11 16:33:37,523 DEBUG [ResourceTransportRequestHandler] GET /resource/42467/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [Host: 192.168.1.101,Date: Sat, Feb 11 16:34:25 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-02-11 16:33:37,523 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.149
2012-02-11 16:33:37,525 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:33:37,527 DEBUG [ResourceTransportRequestHandler] Request for resource 42467 and type 'MEDIA_ITEM' received
2012-02-11 16:33:37,527 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 42467 (local)
2012-02-11 16:33:37,527 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 42467)
2012-02-11 16:33:37,527 DEBUG [VideoDAOImpl] Reading a Video (id = 42467)
2012-02-11 16:33:37,528 DEBUG [VideoDeliveryEngine] Delivering item '42467' for client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:33:37,528 DEBUG [VideoDeliveryEngine] Delivering file 'The Movie.[2011].avi' using transcoding
2012-02-11 16:33:37,528 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:33:37,530 DEBUG [MediaItemDAOImpl] Getting file of media item 42467
2012-02-11 16:33:37,531 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: M:\Movies\M\The Movie (2011)\The Movie.[2011].avi
2012-02-11 16:33:37,532 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i M:\Movies\M\The Movie (2011)\The Movie.[2011].avi -y -threads 1 -async 1 -vcodec mpeg2video -b 8192k -maxrate 8192k -bufsize 5097k -r 24000/1001 -g 15 -copyts -acodec mp2 -ab 128k -ac 2 -map 0:0 -map 0:1 -sn -f vob C:\Windows\TEMP\Serviio\transcoding-temp-42467-MPEG2PS.stf
2012-02-11 16:33:45,033 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file 'C:\Windows\TEMP\Serviio\transcoding-temp-42467-MPEG2PS.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file 'C:\Windows\TEMP\Serviio\transcoding-temp-42467-MPEG2PS.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:130)
at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:95)
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-02-11 16:33:53,915 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-02-11 16:33:56,685 DEBUG [WebServer] Incoming connection from /192.168.1.149:36169
2012-02-11 16:33:56,693 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.149:
2012-02-11 16:33:56,693 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.149
2012-02-11 16:33:56,889 DEBUG [ContentDirectory] Browse() called for renderer MediaRenderer (profile 6) with parameters: objectID = V_F#FOL_R1, browseFlag = BrowseDirectChildren, filter = *, startIndex = 24, count = 24, sortCriteria =
2012-02-11 16:33:56,889 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2012-02-11 16:33:56,903 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-02-11 16:34:03,794 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek RTL8139/810x Family Fast Ethernet NIC) and address 192.168.1.101, timeout = 0
2012-02-11 16:34:03,794 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 29e0c7b2-e2bf-3f96-91e2-08c360085651
2012-02-11 16:34:05,601 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:01:04
2012-02-11 16:34:05,841 DEBUG [WebServer] Incoming connection from /192.168.1.149:36172
2012-02-11 16:34:05,841 DEBUG [ResourceTransportRequestHandler] GET /resource/42467/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [Host: 192.168.1.101,Date: Sat, Feb 11 16:34:53 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-02-11 16:34:05,841 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.149
2012-02-11 16:34:05,842 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:34:05,842 DEBUG [ResourceTransportRequestHandler] Request for resource 42467 and type 'MEDIA_ITEM' received
2012-02-11 16:34:05,842 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 42467 (local)
2012-02-11 16:34:05,842 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 42467)
2012-02-11 16:34:05,842 DEBUG [VideoDAOImpl] Reading a Video (id = 42467)
2012-02-11 16:34:05,843 DEBUG [VideoDeliveryEngine] Delivering item '42467' for client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:34:05,843 DEBUG [VideoDeliveryEngine] Delivering file 'The Movie.[2011].avi' using transcoding
2012-02-11 16:34:05,843 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:34:06,062 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2012-02-11 16:34:06,105 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Movie.[2011].avi: MPEG_PS_PAL
2012-02-11 16:34:06,105 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Movie.[2011].avi: MPEG_PS_NTSC
2012-02-11 16:34:06,105 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 42467) as read
2012-02-11 16:34:06,154 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2012-02-11 16:34:06,158 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 50000000000
2012-02-11 16:34:06,158 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2012-02-11 16:34:06,158 DEBUG [ResourceTransportRequestHandler] Sending file back
2012-02-11 16:34:06,158 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Sat, 11 Feb 2012 22:34:06 GMT,Server: Windows Vista, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.1-SNAPSHOT,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2012-02-11 16:34:07,935 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2012-02-11 16:34:09,161 DEBUG [WebServer] Incoming connection from /192.168.1.149:36173
2012-02-11 16:34:09,172 DEBUG [ResourceTransportRequestHandler] GET /resource/42467/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [Host: 192.168.1.101,Date: Sat, Feb 11 16:34:57 2012,Accept: */*,User-Agent: DLNADOC/1.00 DIRECTV VIIV devices INTEL_NMPR/2.1,Connection: close]]
2012-02-11 16:34:09,173 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.149
2012-02-11 16:34:09,173 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:34:09,174 DEBUG [ResourceTransportRequestHandler] Request for resource 42467 and type 'MEDIA_ITEM' received
2012-02-11 16:34:09,174 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 42467 (local)
2012-02-11 16:34:09,174 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 42467)
2012-02-11 16:34:09,175 DEBUG [VideoDAOImpl] Reading a Video (id = 42467)
2012-02-11 16:34:09,176 DEBUG [VideoDeliveryEngine] Delivering item '42467' for client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:34:09,176 DEBUG [VideoDeliveryEngine] Delivering file 'The Movie.[2011].avi' using transcoding
2012-02-11 16:34:09,176 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.149, Profile=DirecTV HD-DVR'
2012-02-11 16:34:09,202 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2012-02-11 16:34:09,217 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Movie.[2011].avi: MPEG_PS_PAL
2012-02-11 16:34:09,217 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Movie.[2011].avi: MPEG_PS_NTSC
2012-02-11 16:34:09,217 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 42467) as read
2012-02-11 16:34:09,219 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)