Wed Jun 04, 2014 12:14 pm by KishlorN
I retrieved the logs as you advised:
- Code:
2014-06-04 14:06:25,610 DEBUG [SearchManager] Committing search index
2014-06-04 14:06:25,640 DEBUG [WebServer] I/O error: Connection reset
2014-06-04 14:06:28,828 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:29,933 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:33,270 DEBUG [ServiioHttpService] Incoming request from /192.168.1.45:48061: GET /resource/4830/COVER_IMAGE HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: BubbleUPnP UPnP/1.1,Accept-Encoding: gzip,deflate]]
2014-06-04 14:06:33,271 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.45
2014-06-04 14:06:33,271 DEBUG [ServiioHttpService] Incoming request from /192.168.1.45:60661: GET /resource/4830/COVER_IMAGE HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: BubbleUPnP UPnP/1.1,Accept-Encoding: gzip,deflate]]
2014-06-04 14:06:33,272 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.45
2014-06-04 14:06:33,272 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.45
2014-06-04 14:06:33,273 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.45
2014-06-04 14:06:33,273 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.45, Profile=Generic DLNA profile'
2014-06-04 14:06:33,273 DEBUG [ResourceDeliveryProcessor] Request for resource 4830 and type 'COVER_IMAGE' received
2014-06-04 14:06:33,273 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 4830)
2014-06-04 14:06:33,274 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.45, Profile=Generic DLNA profile'
2014-06-04 14:06:33,274 DEBUG [ResourceDeliveryProcessor] Request for resource 4830 and type 'COVER_IMAGE' received
2014-06-04 14:06:33,274 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 4830)
2014-06-04 14:06:33,275 DEBUG [CoverImageRetrievalStrategy] Retrieving info of Cover image with id 4830
2014-06-04 14:06:33,275 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 4830)
2014-06-04 14:06:33,276 DEBUG [CoverImageRetrievalStrategy] Retrieving info of Cover image with id 4830
2014-06-04 14:06:33,276 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 4830)
2014-06-04 14:06:33,277 DEBUG [CoverImageRetrievalStrategy] Retrieving Cover image (SD) with id 4830
2014-06-04 14:06:33,277 DEBUG [GETMethodProcessor] Stream entity has length: 4959
2014-06-04 14:06:33,277 DEBUG [CoverImageRetrievalStrategy] Retrieving Cover image (SD) with id 4830
2014-06-04 14:06:33,277 DEBUG [GETMethodProcessor] Stream entity has length: 4959
2014-06-04 14:06:33,278 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: image/jpeg,Content-Length: 4959,Date: Wed, 04 Jun 2014 12:06:33 GMT,Server: Linux, UPnP/1.0 DLNADOC/1.50, Serviio/1.4.1.2,Cache-control: no-cache,transferMode.dlna.org: Interactive,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-06-04 14:06:33,278 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: image/jpeg,Content-Length: 4959,Date: Wed, 04 Jun 2014 12:06:33 GMT,Server: Linux, UPnP/1.0 DLNADOC/1.50, Serviio/1.4.1.2,Cache-control: no-cache,transferMode.dlna.org: Interactive,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-06-04 14:06:33,746 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:34,951 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:35,670 DEBUG [ServiioHttpService] Incoming request from /192.168.1.48:47442: GET /resource/4038/MEDIA_ITEM/MPEG_PS_PAL-0/ORIGINAL HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: close,icy-metadata: 1,User-Agent: fbxmms/1.0 FreeboxPlayer/6.0]]
2014-06-04 14:06:35,671 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.48
2014-06-04 14:06:35,672 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.48
2014-06-04 14:06:35,673 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.1.48, Profile=Generic DLNA profile'
2014-06-04 14:06:35,673 DEBUG [ResourceDeliveryProcessor] Request for resource 4038 and type 'MEDIA_ITEM' received
2014-06-04 14:06:35,673 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 4038 (local)
2014-06-04 14:06:35,673 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 4038)
2014-06-04 14:06:35,688 DEBUG [VideoDAOImpl] Reading a Video (id = 4038)
2014-06-04 14:06:35,689 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 4038, format MPEG_PS_PAL and profile Generic DLNA profile
2014-06-04 14:06:35,689 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,690 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:35,690 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,691 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:35,691 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Movie_srt.mkv
2014-06-04 14:06:35,691 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,691 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:35,692 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Movie_srt.mkv: MPEG_PS_PAL
2014-06-04 14:06:35,692 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Movie_srt.mkv: MPEG_PS_NTSC
2014-06-04 14:06:35,693 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 4038 (local)
2014-06-04 14:06:35,693 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 4038)
2014-06-04 14:06:35,693 DEBUG [VideoDAOImpl] Reading a Video (id = 4038)
2014-06-04 14:06:35,694 DEBUG [VideoDeliveryEngine] Delivering item '4038' for client 'Identifier=192.168.1.48, Profile=Generic DLNA profile'
2014-06-04 14:06:35,694 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,695 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:35,695 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,695 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:35,696 DEBUG [VideoDeliveryEngine] Delivering file 'Movie_srt.mkv' using transcoding
2014-06-04 14:06:35,696 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,696 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:35,697 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:35,698 DEBUG [FFMPEGWrapper] Invoking FFMPEG to extract subtitle file from: Movie_srt.mkv
2014-06-04 14:06:35,698 DEBUG [ProcessExecutor] Starting ffmpeg -i /home/media/Video/Movie_srt.mkv -y -an -vn -map 0:0 -c:s copy -f srt /tmp/Serviio/subtitles_4038_0.srt
2014-06-04 14:06:38,032 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'Identifier=192.168.1.48, Profile=Generic DLNA profile'
2014-06-04 14:06:38,033 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:38,035 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /home/media/Video/Movie_srt.mkv
2014-06-04 14:06:38,036 DEBUG [ProcessExecutor] Starting ffmpeg -threads 1 -i /home/media/Video/Movie_srt.mkv -y -threads 1 -c:v mpeg2video -pix_fmt yuv420p -qscale:v 1 -vf subtitles=filename='/tmp/Serviio/subtitles_4038_0.srt':original_size=1280x720:charenc=UTF-8 -r 24000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:1 -map 0:2 -sn -f vob /tmp/Serviio/transcoding-temp-4038-1-ORIGINAL-67cc4a683d5bbb86bc7efa6e87b89724.stf
2014-06-04 14:06:38,537 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2014-06-04 14:06:38,539 DEBUG [MediaItemDAOImpl] Getting file of media item 4038
2014-06-04 14:06:38,540 DEBUG [SubtitlesService] Found embedded subtitle track: 0 (eng)
2014-06-04 14:06:38,540 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Movie_srt.mkv: MPEG_PS_PAL
2014-06-04 14:06:38,540 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Movie_srt.mkv: MPEG_PS_NTSC
2014-06-04 14:06:38,541 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 4038) as read
2014-06-04 14:06:38,571 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2014-06-04 14:06:38,571 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2014-06-04 14:06:38,571 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2014-06-04 14:06:38,572 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Wed, 04 Jun 2014 12:06:38 GMT,Server: Linux, UPnP/1.0 DLNADOC/1.50, Serviio/1.4.1.2,Cache-control: no-cache,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-06-04 14:06:38,860 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:39,864 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:40,923 DEBUG [WebServer] I/O error: Connection reset
2014-06-04 14:06:43,674 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:44,575 DEBUG [ServiioHttpService] Incoming request from /192.168.1.48:47454: POST /serviceControl HTTP/1.1, headers = [User-Agent: Linux/2.6 UPnP/1.0 fbxupnpav/6.0,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse",Host: 192.168.1.3:8895,Content-Type: text/xml; charset="utf-8",Content-Length: 539]]
2014-06-04 14:06:44,575 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.48
2014-06-04 14:06:44,576 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.48
2014-06-04 14:06:44,576 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.48
2014-06-04 14:06:44,589 DEBUG [ContentDirectory] Browse() called for renderer Freebox (profile 1) with parameters: objectID = 0, browseFlag = BrowseDirectChildren, filter = @id,@parentID,@childCount,dc:title,dc:date,res,res@protocolInfo,res@size,sec:CaptionInfoEx, startIndex = 0, count = 1000, sortCriteria =
2014-06-04 14:06:44,589 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2014-06-04 14:06:44,596 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-06-04 14:06:44,617 DEBUG [ServiioHttpService] Incoming request from /192.168.1.48:47455: POST /serviceControl HTTP/1.1, headers = [User-Agent: Linux/2.6 UPnP/1.0 fbxupnpav/6.0,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse",Host: 192.168.1.3:8895,Content-Type: text/xml; charset="utf-8",Content-Length: 539]]
2014-06-04 14:06:44,617 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.48
2014-06-04 14:06:44,619 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.48
2014-06-04 14:06:44,619 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.48
2014-06-04 14:06:44,630 DEBUG [ContentDirectory] Browse() called for renderer Freebox (profile 1) with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = @id,@parentID,@childCount,dc:title,dc:date,res,res@protocolInfo,res@size,sec:CaptionInfoEx, startIndex = 0, count = 1000, sortCriteria =
2014-06-04 14:06:44,630 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2014-06-04 14:06:44,631 DEBUG [VideoDAOImpl] Retrieving number of video initials [No_Restriction]
2014-06-04 14:06:44,655 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:service:ContentDirectory:1 from address /192.168.1.48:32807s
2014-06-04 14:06:44,656 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.1.48:32807
2014-06-04 14:06:44,692 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2 [No_Restriction]
2014-06-04 14:06:44,749 DEBUG [SeriesDAOImpl] Retrieving number of series
2014-06-04 14:06:44,765 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO [No_Restriction]
2014-06-04 14:06:44,802 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos [No_Restriction]
2014-06-04 14:06:44,869 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2014-06-04 14:06:44,869 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0 [No_Restriction]
2014-06-04 14:06:44,881 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-06-04 14:06:44,969 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2014-06-04 14:06:45,041 DEBUG [VideoDAOImpl] Retrieving number of video ratings [No_Restriction]
2014-06-04 14:06:45,085 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2014-06-04 14:06:45,213 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2014-06-04 14:06:45,223 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2014-06-04 14:06:45,229 DEBUG [VideoDAOImpl] Retrieving number of video release years [No_Restriction]
2014-06-04 14:06:45,255 DEBUG [PlaylistDAOImpl] Retrieving number of playlists for VIDEO [No_Restriction]
2014-06-04 14:06:45,269 DEBUG [OnlineRepositoryDAOImpl] Retrieving list of [FEED, WEB_RESOURCE] OnlineRepositories for VIDEO [No_Restriction]
2014-06-04 14:06:45,299 DEBUG [OnlineRepositoryDAOImpl] Retrieving list of [LIVE_STREAM] OnlineRepositories for VIDEO [No_Restriction]