I get error if using embedded subtitles,

Mon Mar 04, 2013 2:37 pm by zyxos

I'm so happy for the latest version but I've a problem with embedded subtitles. MKV files with external subtitles are working. However, I turned on all subtitles in Serviio and typed swe as a preferred subtitles code. I will get a error about server. If I leave preferred subtitles, the movie are showing but no subtitles. Why is that? I want swedish subtitles inside mkv...

How can I fix this? I'm a beginner.

Mon Mar 04, 2013 2:45 pm by Illico

Could you enable serviio detailed logging and find the section when you start to play this mkv file on your renderer, and post log here ?

Does your media are stored in a remote path ?

Mon Mar 04, 2013 2:58 pm by zyxos

Illico wrote:Could you enable serviio detailed logging and find the section when you start to play this mkv file on your renderer, and post log here ?

Does your media are stored in a remote path ?

Thanks for your reply.

After editing, suddenly subtitles are showing when typing swe in preferred subtitles. Was it because I edited? Thank you so much!

Edit: No, it deosn't work anymore.

Mon Mar 04, 2013 4:17 pm by Illico

Could you retry and found the command line where subtitle was extracted from mkv, probably just before the line
2013-03-04 16:11:02,256 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-322,5,main]
I suspect that subtitle extraction was too long !

Does "D:" is a remote path ?

Then could you try the same command line in a windows terminal and check how many time the extraction process took?

Mon Mar 04, 2013 4:55 pm by zyxos

Illico wrote:Could you retry and found the command line where subtitle was extracted from mkv, probably just before the line
2013-03-04 16:11:02,256 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-322,5,main]
I suspect that subtitle extraction was too long !

Does "D:" is a remote path ?

Then could you try the same command line in a windows terminal and check how many time the extraction process took?

It really showed swedish subtitles right after I edited to debug, but it didn't work anymore after I restarted Serviio.

Mon Mar 04, 2013 5:01 pm by zip

this doesn't include any playback logs, search your log for SubtitleService entries

Tue Mar 05, 2013 11:45 am by zyxos

zip wrote:this doesn't include any playback logs, search your log for SubtitleService entries

I reinstalled both Serviio and Java, everything worked with subtitles too, but if I change an other movie like MIB 3, I get error. Now I get error all the time, even the first movie.

The log is too much, but I found SubtitlesService where you can find at 12:31:12,855:

2013-03-05 12:31:00,993 DEBUG [ResourceTransportRequestHandler] HEAD /resource/28/MEDIA_ITEM/MPEG_TS_SD_EU_ISO-0/ORIGINAL HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";,Host: 192.168.0.10:8895]]
2013-03-05 12:31:00,993 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:00,994 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:00,994 DEBUG [ResourceDeliveryProcessor] Request for resource 28 and type 'MEDIA_ITEM' received
2013-03-05 12:31:00,994 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 28 (local)
2013-03-05 12:31:00,994 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 28)
2013-03-05 12:31:00,994 DEBUG [VideoDAOImpl] Reading a Video (id = 28)
2013-03-05 12:31:00,995 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 28, format MPEG_TS_SD_EU_ISO and profile Sony Bravia TV (2012)
2013-03-05 12:31:00,995 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:00,995 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:00,995 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:00,995 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:00,995 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv
2013-03-05 12:31:00,995 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:00,996 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:00,996 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:31:00,996 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:31:00,996 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:31:00,996 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Tue, 05 Mar 2013 11:31:00 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.2,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_EU_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-03-05 12:31:01,012 DEBUG [WebServer] Incoming connection from /192.168.0.13:46684
2013-03-05 12:31:01,013 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:01,013 DEBUG [ResourceTransportRequestHandler] GET /resource/28/MEDIA_ITEM/MPEG_TS_SD_EU_ISO-0/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";,Host: 192.168.0.10:8895]]
2013-03-05 12:31:01,013 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:01,013 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:01,014 DEBUG [ResourceDeliveryProcessor] Request for resource 28 and type 'MEDIA_ITEM' received
2013-03-05 12:31:01,014 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 28 (local)
2013-03-05 12:31:01,014 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 28)
2013-03-05 12:31:01,014 DEBUG [VideoDAOImpl] Reading a Video (id = 28)
2013-03-05 12:31:01,014 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 28, format MPEG_TS_SD_EU_ISO and profile Sony Bravia TV (2012)
2013-03-05 12:31:01,014 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,015 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:01,015 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,015 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:01,015 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv
2013-03-05 12:31:01,015 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,015 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:01,015 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:31:01,016 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:31:01,016 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:31:01,016 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 28 (local)
2013-03-05 12:31:01,016 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 28)
2013-03-05 12:31:01,016 DEBUG [VideoDAOImpl] Reading a Video (id = 28)
2013-03-05 12:31:01,016 DEBUG [VideoDeliveryEngine] Delivering item '28' for client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:01,016 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,017 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:01,017 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,017 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:01,017 DEBUG [VideoDeliveryEngine] Delivering file 'The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv' using transcoding
2013-03-05 12:31:01,017 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,017 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:01,018 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:01,018 DEBUG [FFMPEGWrapper] Invoking FFMPEG to extract subtitle file from: The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv
2013-03-05 12:31:01,018 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\lib/ffmpeg.exe -i D:\Filmer\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv -y -an -vn -map 0:3 -c:s copy -f srt C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_28_3.srt
2013-03-05 12:31:10,414 DEBUG [AbstractTranscodingDeliveryEngine] No other client uses transcoding job of file 'transcoding-temp-28-sony2012-ORIGINAL.stf', will stop the job
2013-03-05 12:31:10,414 DEBUG [AbstractTranscodingDeliveryEngine] Stopping previous transcoding job of file 'transcoding-temp-28-sony2012-ORIGINAL.stf'
2013-03-05 12:31:10,414 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-1318,5,main]
2013-03-05 12:31:10,442 DEBUG [ProcessUtils] Killing the Windows process: 3796
2013-03-05 12:31:10,577 DEBUG [TranscodingJobListener] Transcoding finished; successful: true
2013-03-05 12:31:11,524 DEBUG [TranscodingJobListener] Deleted temp file 'C:\Users\Ersin\AppData\Local\Temp\Serviio\transcoding-temp-28-sony2012-ORIGINAL.stf': true
2013-03-05 12:31:11,524 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:11,524 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:11,525 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: D:\Filmer\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv
2013-03-05 12:31:11,525 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\lib/ffmpeg.exe -threads auto -i D:\Filmer\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv -y -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -vf pad=1280:720:0:92:black,setdar=4:3,subtitles=filename="C\\:/Users/Ersin/AppData/Local/Temp/Serviio/subtitles_28_3.srt":original_size=1280x536 -r 24000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts C:\Users\Ersin\AppData\Local\Temp\Serviio\transcoding-temp-28-sony2012-ORIGINAL-658924544d02f774ead5ee2c4b2375ba.stf
2013-03-05 12:31:12,025 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-03-05 12:31:12,026 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,026 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,026 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:31:12,026 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:31:12,026 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:31:12,026 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 28) as read
2013-03-05 12:31:12,028 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-03-05 12:31:12,028 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-03-05 12:31:12,029 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-03-05 12:31:12,029 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Tue, 05 Mar 2013 11:31:12 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.2,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-10177/10177,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_EU_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-03-05 12:31:12,841 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-03-05 12:31:12,849 DEBUG [WebServer] Incoming connection from /192.168.0.13:46686
2013-03-05 12:31:12,852 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:12,852 DEBUG [ResourceTransportRequestHandler] GET /resource/28/MEDIA_ITEM/MPEG_TS_SD_EU_ISO-0/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";,Host: 192.168.0.10:8895]]
2013-03-05 12:31:12,852 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:12,852 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:12,852 DEBUG [ResourceDeliveryProcessor] Request for resource 28 and type 'MEDIA_ITEM' received
2013-03-05 12:31:12,852 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 28 (local)
2013-03-05 12:31:12,852 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 28)
2013-03-05 12:31:12,853 DEBUG [VideoDAOImpl] Reading a Video (id = 28)
2013-03-05 12:31:12,853 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 28, format MPEG_TS_SD_EU_ISO and profile Sony Bravia TV (2012)
2013-03-05 12:31:12,853 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,854 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,854 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,854 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,854 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv
2013-03-05 12:31:12,854 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,855 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,855 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:31:12,855 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:31:12,855 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:31:12,855 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 28 (local)
2013-03-05 12:31:12,855 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 28)
2013-03-05 12:31:12,855 DEBUG [VideoDAOImpl] Reading a Video (id = 28)
2013-03-05 12:31:12,856 DEBUG [VideoDeliveryEngine] Delivering item '28' for client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:12,856 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,856 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,856 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,856 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,856 DEBUG [VideoDeliveryEngine] Delivering file 'The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv' using transcoding
2013-03-05 12:31:12,856 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,857 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:12,857 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:12,857 DEBUG [FFMPEGWrapper] Invoking FFMPEG to extract subtitle file from: The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv
2013-03-05 12:31:12,857 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\lib/ffmpeg.exe -i D:\Filmer\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv -y -an -vn -map 0:3 -c:s copy -f srt C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_28_3.srt
2013-03-05 12:31:17,557 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:17,557 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-03-05 12:31:17,557 DEBUG [MediaItemDAOImpl] Getting file of media item 28
2013-03-05 12:31:17,558 DEBUG [SubtitlesService] Found embedded subtitle track: 3 (swe)
2013-03-05 12:31:17,558 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:31:17,558 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:31:17,558 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:31:17,558 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 28) as read
2013-03-05 12:31:17,560 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-03-05 12:31:17,560 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-03-05 12:31:17,561 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-03-05 12:31:17,561 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Tue, 05 Mar 2013 11:31:17 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.2,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-10177/10177,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_EU_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-03-05 12:31:40,552 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-03-05 12:31:40,898 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-03-05 12:31:40,898 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-03-05 12:31:41,628 DEBUG [WebServer] Incoming connection from /192.168.0.13:46703
2013-03-05 12:31:41,629 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:41,629 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:41,629 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:41,829 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1$F27, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:41,829 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:41,829 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:41,832 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:43,084 DEBUG [WebServer] Incoming connection from /192.168.0.13:46705
2013-03-05 12:31:43,084 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,084 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:43,084 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,287 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:43,287 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:43,288 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:43,291 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:43,326 DEBUG [WebServer] Incoming connection from /192.168.0.13:46706
2013-03-05 12:31:43,327 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,327 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:43,327 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,542 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 10, count = 10, sortCriteria =
2013-03-05 12:31:43,542 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:43,542 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:43,545 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:43,583 DEBUG [WebServer] Incoming connection from /192.168.0.13:46707
2013-03-05 12:31:43,587 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,588 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:43,588 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,823 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 20, count = 10, sortCriteria =
2013-03-05 12:31:43,823 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:43,823 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:43,827 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:43,935 DEBUG [WebServer] Incoming connection from /192.168.0.13:46708
2013-03-05 12:31:43,936 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:43,936 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:43,936 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,141 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:44,141 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:44,142 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:44,148 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:44,171 DEBUG [WebServer] Incoming connection from /192.168.0.13:46709
2013-03-05 12:31:44,171 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,172 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:44,172 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,372 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 10, count = 10, sortCriteria =
2013-03-05 12:31:44,372 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:44,372 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:44,375 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:44,436 DEBUG [WebServer] Incoming connection from /192.168.0.13:46710
2013-03-05 12:31:44,437 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,437 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:44,437 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,650 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 20, count = 10, sortCriteria =
2013-03-05 12:31:44,650 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:44,651 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:44,655 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:44,727 DEBUG [WebServer] Incoming connection from /192.168.0.13:46711
2013-03-05 12:31:44,727 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,727 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:44,727 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:44,938 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:44,938 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:44,939 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:45,011 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:45,039 DEBUG [WebServer] Incoming connection from /192.168.0.13:46712
2013-03-05 12:31:45,129 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:45,130 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:45,130 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:45,352 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 10, count = 10, sortCriteria =
2013-03-05 12:31:45,352 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:45,352 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:45,355 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:45,419 DEBUG [WebServer] Incoming connection from /192.168.0.13:46713
2013-03-05 12:31:45,419 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:45,420 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:45,420 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:45,623 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 20, count = 10, sortCriteria =
2013-03-05 12:31:45,623 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:45,623 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:45,627 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:57,204 DEBUG [WebServer] Incoming connection from /192.168.0.13:46714
2013-03-05 12:31:57,204 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,204 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:57,204 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,410 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1$F7, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:57,410 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:57,411 DEBUG [FolderDAOImpl] Getting number of sub-Folders in folder 7 [No_Restriction]
2013-03-05 12:31:57,416 DEBUG [VideoDAOImpl] Retrieving list of videos for folder 7 (from=0, count=10) [No_Restriction]
2013-03-05 12:31:57,418 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:31:57,418 DEBUG [GenreDAOImpl] Reading a Genre (id = 3)
2013-03-05 12:31:57,419 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2013-03-05 12:31:57,419 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7 and profile Sony Bravia TV (2012)
2013-03-05 12:31:57,419 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:31:57,419 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:31:57,419 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:31:57,420 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:31:57,420 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:31:57,420 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:31:57,420 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:31:57,420 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 7)
2013-03-05 12:31:57,420 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:31:57,421 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 7 (filter: ALL) [No_Restriction]
2013-03-05 12:31:57,421 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2013-03-05 12:31:57,425 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:57,470 DEBUG [WebServer] Incoming connection from /192.168.0.13:46715
2013-03-05 12:31:57,470 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,471 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:57,471 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,530 DEBUG [WebServer] Incoming connection from /192.168.0.13:46716
2013-03-05 12:31:57,530 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,530 DEBUG [ResourceTransportRequestHandler] GET /resource/7/COVER_IMAGE HTTP/1.1, headers = [transferMode.dlna.org: Interactive,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";,Host: 192.168.0.10:8895]]
2013-03-05 12:31:57,530 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,530 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:31:57,531 DEBUG [ResourceDeliveryProcessor] Request for resource 7 and type 'COVER_IMAGE' received
2013-03-05 12:31:57,531 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 7)
2013-03-05 12:31:57,531 DEBUG [CoverImageRetrievalStrategy] Retrieving info of Cover image with id 7
2013-03-05 12:31:57,531 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 7)
2013-03-05 12:31:57,531 DEBUG [CoverImageRetrievalStrategy] Retrieving Cover image with id 7
2013-03-05 12:31:57,531 DEBUG [GETMethodProcessor] Stream entity has length: 4763
2013-03-05 12:31:57,531 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: image/jpeg,Content-Length: 4763,Date: Tue, 05 Mar 2013 11:31:57 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.2,Cache-control: no-cache,transferMode.dlna.org: Interactive,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-03-05 12:31:57,681 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1$F7, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:57,681 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:57,682 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:57,684 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:57,740 DEBUG [WebServer] Incoming connection from /192.168.0.13:46717
2013-03-05 12:31:57,740 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,740 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:57,740 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:57,952 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1$F7, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:57,952 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:57,952 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:57,954 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:31:58,122 DEBUG [WebServer] Incoming connection from /192.168.0.13:46718
2013-03-05 12:31:58,152 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:58,153 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:31:58,153 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:31:58,366 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1$F7, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:31:58,366 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:31:58,366 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:31:58,369 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:32:00,311 DEBUG [WebServer] Incoming connection from /192.168.0.13:46725
2013-03-05 12:32:00,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:00,312 DEBUG [ResourceTransportRequestHandler] HEAD /resource/7/MEDIA_ITEM/MPEG_TS_SD_EU_ISO-0/ORIGINAL HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";,Host: 192.168.0.10:8895]]
2013-03-05 12:32:00,312 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:00,313 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:32:00,313 DEBUG [ResourceDeliveryProcessor] Request for resource 7 and type 'MEDIA_ITEM' received
2013-03-05 12:32:00,313 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2013-03-05 12:32:00,313 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2013-03-05 12:32:00,313 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2013-03-05 12:32:00,314 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7, format MPEG_TS_SD_EU_ISO and profile Sony Bravia TV (2012)
2013-03-05 12:32:00,314 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,314 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,315 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,315 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,315 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv
2013-03-05 12:32:00,315 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,315 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,315 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:32:00,315 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:32:00,315 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:32:00,316 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Tue, 05 Mar 2013 11:32:00 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.2,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_EU_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-03-05 12:32:00,327 DEBUG [WebServer] Incoming connection from /192.168.0.13:46726
2013-03-05 12:32:00,327 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:00,327 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_TS_SD_EU_ISO-0/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";,Host: 192.168.0.10:8895]]
2013-03-05 12:32:00,327 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:00,327 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:32:00,328 DEBUG [ResourceDeliveryProcessor] Request for resource 7 and type 'MEDIA_ITEM' received
2013-03-05 12:32:00,328 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2013-03-05 12:32:00,328 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2013-03-05 12:32:00,328 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2013-03-05 12:32:00,328 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7, format MPEG_TS_SD_EU_ISO and profile Sony Bravia TV (2012)
2013-03-05 12:32:00,328 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,329 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,329 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,329 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,329 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv
2013-03-05 12:32:00,329 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,330 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,330 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:32:00,330 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:32:00,330 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:32:00,330 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2013-03-05 12:32:00,330 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2013-03-05 12:32:00,330 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2013-03-05 12:32:00,331 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:32:00,331 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,332 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,332 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,332 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,332 DEBUG [VideoDeliveryEngine] Delivering file 'Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv' using transcoding
2013-03-05 12:32:00,332 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,333 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:00,333 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:00,333 DEBUG [FFMPEGWrapper] Invoking FFMPEG to extract subtitle file from: Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv
2013-03-05 12:32:00,333 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\lib/ffmpeg.exe -i D:\Filmer\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv -y -an -vn -map 0:0 -c:s copy -f srt C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_7_0.srt
2013-03-05 12:32:03,714 DEBUG [LicenseValidator] Validating license file (350 bytes)
2013-03-05 12:32:06,693 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.10, timeout = 0
2013-03-05 12:32:06,693 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c40a518f-6e4b-3d4a-b2ea-f39bd2e8b629
2013-03-05 12:32:07,442 DEBUG [WebServer] Incoming connection from /192.168.0.13:35325
2013-03-05 12:32:07,442 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,442 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c40a518f-6e4b-3d4a-b2ea-f39bd2e8b629 from 192.168.0.13 (headers = [Host: 192.168.0.10:8895,Date: Tue, 05 Mar 2013 11:32:06 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";])
2013-03-05 12:32:07,442 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,442 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.10:8895,Date: Tue, 05 Mar 2013 11:32:06 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";]
2013-03-05 12:32:07,442 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,442 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2012)'
2013-03-05 12:32:07,457 DEBUG [WebServer] Incoming connection from /192.168.0.13:35326
2013-03-05 12:32:07,457 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,457 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-03-05 12:32:07,457 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-03-05 12:32:07,488 DEBUG [WebServer] Incoming connection from /192.168.0.13:35327
2013-03-05 12:32:07,488 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,488 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-03-05 12:32:07,488 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-03-05 12:32:07,520 DEBUG [WebServer] Incoming connection from /192.168.0.13:35328
2013-03-05 12:32:07,520 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,520 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.13
2013-03-05 12:32:07,520 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,754 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:32:07,769 DEBUG [WebServer] Incoming connection from /192.168.0.13:35329
2013-03-05 12:32:07,769 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:07,769 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.13
2013-03-05 12:32:07,769 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,019 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2012)'
2013-03-05 12:32:08,019 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:32:08,112 DEBUG [WebServer] Incoming connection from /192.168.0.13:35330
2013-03-05 12:32:08,112 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,112 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c40a518f-6e4b-3d4a-b2ea-f39bd2e8b629 from 192.168.0.13 (headers = [Host: 192.168.0.10:8895,Date: Tue, 05 Mar 2013 11:32:07 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";])
2013-03-05 12:32:08,112 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,112 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.10:8895,Date: Tue, 05 Mar 2013 11:32:07 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-55HX855";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-55HX855"; mv="1.7";]
2013-03-05 12:32:08,112 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,112 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2012)'
2013-03-05 12:32:08,128 DEBUG [WebServer] Incoming connection from /192.168.0.13:35331
2013-03-05 12:32:08,128 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,128 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-03-05 12:32:08,128 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-03-05 12:32:08,159 DEBUG [WebServer] Incoming connection from /192.168.0.13:35332
2013-03-05 12:32:08,159 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,159 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-03-05 12:32:08,159 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-03-05 12:32:08,190 DEBUG [WebServer] Incoming connection from /192.168.0.13:35333
2013-03-05 12:32:08,190 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,190 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.13
2013-03-05 12:32:08,190 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,471 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:32:08,471 DEBUG [WebServer] Incoming connection from /192.168.0.13:35334
2013-03-05 12:32:08,471 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,471 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.13
2013-03-05 12:32:08,471 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:08,658 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:04:04 (advertisement duration is 1800 sec.)
2013-03-05 12:32:08,707 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2012)'
2013-03-05 12:32:08,711 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:32:13,985 DEBUG [ServiioConsole] Reading application data
2013-03-05 12:32:30,334 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-1418,5,main]
2013-03-05 12:32:30,334 DEBUG [ProcessUtils] Killing the Windows process: 5020
2013-03-05 12:32:30,340 DEBUG [AbstractTranscodingDeliveryEngine] No other client uses transcoding job of file 'transcoding-temp-28-sony2012-ORIGINAL-658924544d02f774ead5ee2c4b2375ba.stf', will stop the job
2013-03-05 12:32:30,340 DEBUG [AbstractTranscodingDeliveryEngine] Stopping previous transcoding job of file 'transcoding-temp-28-sony2012-ORIGINAL-658924544d02f774ead5ee2c4b2375ba.stf'
2013-03-05 12:32:30,340 DEBUG [ProcessExecutor] Stopping external process: Thread[Thread-1393,5,main]
2013-03-05 12:32:30,340 DEBUG [ProcessUtils] Killing the Windows process: 2416
2013-03-05 12:32:30,344 DEBUG [TranscodingJobListener] Transcoding finished; successful: true
2013-03-05 12:32:30,399 DEBUG [TranscodingJobListener] Deleted temp file 'C:\Users\Ersin\AppData\Local\Temp\Serviio\transcoding-temp-28-sony2012-ORIGINAL-658924544d02f774ead5ee2c4b2375ba.stf': true
2013-03-05 12:32:30,399 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'Identifier=192.168.0.13, Profile=Sony Bravia TV (2012)'
2013-03-05 12:32:30,399 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:30,399 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: D:\Filmer\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv
2013-03-05 12:32:30,400 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\lib/ffmpeg.exe -threads auto -i D:\Filmer\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv -y -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -vf pad=1280:720:0:96:black,setdar=4:3,subtitles=filename="C\\:/Users/Ersin/AppData/Local/Temp/Serviio/subtitles_7_0.srt":original_size=1280x528 -r 24000/1001 -g 15 -c:a copy -map 0:1 -map 0:2 -sn -f mpegts C:\Users\Ersin\AppData\Local\Temp\Serviio\transcoding-temp-7-sony2012-ORIGINAL-290ed23035e7ca765b3fd4c1d9b02828.stf
2013-03-05 12:32:30,900 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2013-03-05 12:32:30,900 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2013-03-05 12:32:30,901 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (swe)
2013-03-05 12:32:30,901 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_EU_ISO
2013-03-05 12:32:30,901 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_NA_ISO
2013-03-05 12:32:30,901 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv: MPEG_TS_SD_KO_ISO
2013-03-05 12:32:30,901 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7) as read
2013-03-05 12:32:30,903 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2013-03-05 12:32:30,904 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2013-03-05 12:32:30,904 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2013-03-05 12:32:30,904 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Tue, 05 Mar 2013 11:32:30 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.2,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-7561/7561,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_EU_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2013-03-05 12:32:30,907 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2013-03-05 12:32:33,326 DEBUG [WebServer] Incoming connection from /192.168.0.13:35338
2013-03-05 12:32:33,326 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:33,326 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.13
2013-03-05 12:32:33,327 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.13
2013-03-05 12:32:33,525 DEBUG [ContentDirectory] Browse() called for renderer KDL-55HX855 (profile sony2012) with parameters: objectID = V_F^FOL_R1$F7, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:rating,upnp:rating@type,upnp:class,res@resolution,res@av:mpfEntries,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2013-03-05 12:32:33,525 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2013-03-05 12:32:33,525 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2013-03-05 12:32:33,528 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2013-03-05 12:32:40,898 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-03-05 12:32:40,898 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-03-05 12:32:41,062 DEBUG [PlaylistDAOImpl] Reading all Playlists
2013-03-05 12:32:41,162 DEBUG [RepositoryDAOImpl] Reading all Repositories
2013-03-05 12:32:41,162 DEBUG [LibraryUpdatesCheckerThread] Looking for removed playlist files in repository: D:\Filmer
2013-03-05 12:32:41,162 DEBUG [PlaylistDAOImpl] Reading Playlists for Repository (id = 1)
2013-03-05 12:32:41,163 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: D:\Filmer
2013-03-05 12:32:41,163 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 1)
2013-03-05 12:32:41,166 DEBUG [MediaItemDAOImpl] Getting file of media item 1

Tue Mar 05, 2013 12:47 pm by zip

how long does this command take?

Code:: ffmpeg.exe -i "D:\Filmer\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv" -y -an -vn -map 0:3 -c:s copy -f srt C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_28_3.srt

Is D drive remote or local drive?

Tue Mar 05, 2013 1:33 pm by zyxos

zip wrote:how long does this command take?

Code:
ffmpeg.exe -i "D:\Filmer\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv" -y -an -vn -map 0:3 -c:s copy -f srt C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_28_3.srt

Is D drive remote or local drive?

D: is a lokal drive. Sorry I didn't understand your question early.

I don't know how long. How to check it up?

Tue Mar 05, 2013 1:46 pm by Illico

zyxos wrote:I don't know how long. How to check it up?

For example:

With Notepad, create and edit a file named "TEST.bat" and copy this following lines:

Code:: @echo off echo %TIME% "C:\Program Files\Serviio\lib\ffmpeg.exe" -i "D:\Filmer\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv" -y -an -vn -map 0:0 -c:s copy -f srt "C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_7_0.srt" echo %TIME% pause

Execute this file (double-click on TEST.bat), a windows terminal will open and execute the ffmpeg command line. Then check the time before and after

Tue Mar 05, 2013 3:29 pm by zyxos

Illico wrote:
zyxos wrote:I don't know how long. How to check it up?

For example:

With Notepad, create and edit a file named "TEST.bat" and copy this following lines:
Code:
@echo off echo %TIME% "C:\Program Files\Serviio\lib\ffmpeg.exe" -i "D:\Filmer\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver\Iron.Man.2008.SWESUB.720p.BluRay.x264-Oliver.mkv" -y -an -vn -map 0:0 -c:s copy -f srt "C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_7_0.srt" echo %TIME% pause

Execute this file (double-click on TEST.bat), a windows terminal will open and execute the ffmpeg command line. Then check the time before and after

Uploaded an image:

http://postimage.org/image/6nciwsdvn/full/

Tue Mar 05, 2013 4:34 pm by Illico

39.93 second to extract subtitle on a local path, it is too long (>30s).
Zip, any idea ?

@zyxos Could you try to copy your mkv file on C: and retry the same command line (by changing D: -> C: on the Test.bat ) ?

Tue Mar 05, 2013 5:45 pm by zyxos

Illico wrote:39.93 second to extract subtitle on a local path, it is too long (>30s).
Zip, any idea ?

@zyxos Could you try to copy your mkv file on C: and retry the same command line (by changing D: -> C: on the Test.bat ) ?

I only have 10 GB memory left on C: but I tried to move the hobbit to C:
This is the result in test.bat (changed from D: to C:):

"Output file #0 does not contain any stream"

I edited:

@echo off
echo %TIME%
"C:\Program Files\Serviio\lib\ffmpeg.exe" -i "C:\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv" -y -an -vn -map 0:0 -c:s copy -f srt "C:\Users\Ersin\AppData\Local\Temp\Serviio\subtitles_7_0.srt"
echo %TIME%
pause

Tue Mar 05, 2013 7:12 pm by Illico

Did you move your mkv file to "C:\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv" path ?

Tue Mar 05, 2013 7:22 pm by zyxos

Illico wrote:Did you move your mkv file to "C:\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy\The.Hobbit.An.Unexpected.Journey.2012.SWESUB.720p.BluRay.x264-Mr.Stiffy.mkv" path ?

Yes.

I don't know if java causes this because sometimes I get errors about java when starting serviio after restarting computer. Then I reinstall java andit works.

Tue Mar 05, 2013 7:29 pm by zip

I tried on the same file (hobbit) and it looks like the embedded subtitles functionality is quite flawed at this moment. Some mkv's work well, some take a long time to extract the subs, which in turn causes the subs either not be present, or end up with an error on the TV.

This file took about 30 seconds on my laptop, so it's not as straight forward as I thought. it might be due to the file being huge (6GB), high bitrate (HD), or similar. It starts quite fast, but then slows down in the middle. Not sure if there are any FFmpeg command that would make it faster.

Tue Mar 05, 2013 8:41 pm by zyxos

zip wrote:I tried on the same file (hobbit) and it looks like the embedded subtitles functionality is quite flawed at this moment. Some mkv's work well, some take a long time to extract the subs, which in turn causes the subs either not be present, or end up with an error on the TV.

This file took about 30 seconds on my laptop, so it's not as straight forward as I thought. it might be due to the file being huge (6GB), high bitrate (HD), or similar. It starts quite fast, but then slows down in the middle. Not sure if there are any FFmpeg command that would make it faster.

Okay, so there is nothing I can do atm?

Tue Mar 05, 2013 8:44 pm by zip

It seems to me that sometimes the command finishes faster than other times. Not really sure why.

Wed Mar 06, 2013 1:08 am by chris_glen

Hi,
I am having similar problems with 1.2 and subtitles.
If subtitles are enabled on the serviio server then no files with subtitles associated will play and the player brings up an error. On my Sony tv it says 'server is temporary unavailable', using dlna on vlc it says 'cannot access stream', using dlna on android says 'cannot play media file'. I have tried mkv embedded, external, as well as mp4 external .srt & .ass . I have played around with turning on & off settings for burned-in subtitles and extraction of embedded subtitles, however no combination of options has worked.
Playing files with subtitles does work only with the serviio android app.

All files play on all devices if subtitles are not enabled on serviio.

With 1.1 files with subtitles did play on all devices (with the exception of the sony tv of course).

Wed Mar 06, 2013 11:08 am by zip

chris_glen wrote:Hi,
I am having similar problems with 1.2 and subtitles.
If subtitles are enabled on the serviio server then no files with subtitles associated will play and the player brings up an error. On my Sony tv it says 'server is temporary unavailable', using dlna on vlc it says 'cannot access stream', using dlna on android says 'cannot play media file'. I have tried mkv embedded, external, as well as mp4 external .srt & .ass . I have played around with turning on & off settings for burned-in subtitles and extraction of embedded subtitles, however no combination of options has worked.
Playing files with subtitles does work only with the serviio android app.

All files play on all devices if subtitles are not enabled on serviio.

With 1.1 files with subtitles did play on all devices (with the exception of the sony tv of course).

Please, people, post your logs, as per READ ME FIRST topic.

I get error if using embedded subtitles,

I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Re: I get error if using embedded subtitles,

Who is online