Issue with Pause for Panasonic Blueray - BDT110
Hi,
I have a panasonic blue-ray player DMP-BDT110 and I'm trying to get it working using Serviio. I found it easy to install and I have it working.
To get it working I created the profile from this item - and it seemed to work! Fantasic
viewtopic.php?f=13&t=1218&start=40
I was able to browse my library of files and play a file. It played well with no issues until I tried to pause or fast forward it.
When I did this, it change the aspect ratio to a 4:3 (old small format) from the wide screen one 16:9, and the playback stopped. No matter what I did, I couldn't get it to restart (i.e. pressed play, etc)
I'm running on XP also.
I turned on debug and this is what I saw, I've tried to put in the log around the time of selecting MovieA.avi.
thanks,
MSJ
2011-10-31 13:09:14,781 INFO [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2011-10-31 13:09:14,796 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-10-31 13:09:14,796 DEBUG [EventDispatcher] Sending event notification #0 for subscription df799be1-cf91-48f0-bd30-ffc8a3d121d6 to endpoint http://192.168.0.12:60605/event_0
2011-10-31 13:09:14,796 DEBUG [RequestExecutor] Sending HTTP request to 192.168.0.12:60605
2011-10-31 13:09:14,796 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-10-31 13:09:14,796 DEBUG [EventDispatcher] Event notification sent and received successfully
2011-10-31 13:09:14,796 DEBUG [EventDispatcher] Sending event notification #1 for subscription dd7d642b-da17-4fdf-9b8b-04fd858cdb4d to endpoint http://192.168.0.10:2869/upnp/eventing/ssfbvdnajb
2011-10-31 13:09:14,796 DEBUG [RequestExecutor] Sending HTTP request to 192.168.0.10:2869
2011-10-31 13:09:14,812 DEBUG [EventDispatcher] Event notification sent and received successfully
2011-10-31 13:09:16,687 DEBUG [WebServer] Incoming connection from /192.168.0.12:1987
2011-10-31 13:09:16,687 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.12:
2011-10-31 13:09:16,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:16,687 DEBUG [ContentDirectory] Browse() called for renderer Unrecognized device (profile 21) with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = @childCount,dc:date,dc:creator,upnp:channelNr,upnp:channelName,upnp:genre,upnp:album,res,res@duration,res@size,res@bitrate,res@dlna:ifoFileURI,res@protocolInfo, startIndex = 0, count = 5, sortCriteria =
2011-10-31 13:09:16,703 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-10-31 13:09:16,718 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-10-31 13:09:16,734 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-10-31 13:09:16,734 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-10-31 13:09:16,750 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-10-31 13:09:16,750 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-10-31 13:09:16,750 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-10-31 13:09:19,125 DEBUG [WebServer] Incoming connection from /192.168.0.12:1988
2011-10-31 13:09:19,125 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.12:
2011-10-31 13:09:19,125 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:19,125 DEBUG [ContentDirectory] Browse() called for renderer Unrecognized device (profile 21) with parameters: objectID = V_LV, browseFlag = BrowseDirectChildren, filter = @childCount,dc:date,dc:creator,upnp:channelNr,upnp:channelName,upnp:genre,upnp:album,res,res@duration,res@size,res@bitrate,res@dlna:ifoFileURI,res@protocolInfo, startIndex = 0, count = 5, sortCriteria =
2011-10-31 13:09:19,125 DEBUG [VideoDAOImpl] Retrieving list of 10 last viewed videos (from=0, count=5)
2011-10-31 13:09:19,140 DEBUG [GenreDAOImpl] Reading a Genre (id = 6)
2011-10-31 13:09:19,156 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:19,156 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2011-10-31 13:09:19,156 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:19,171 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7 and profile Panasonic DMP-BDT110
2011-10-31 13:09:19,171 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:19,171 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:19,171 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 7)
2011-10-31 13:09:19,187 DEBUG [GenreDAOImpl] Reading a Genre (id = 4)
2011-10-31 13:09:19,187 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 24)
2011-10-31 13:09:19,187 DEBUG [MediaItemDAOImpl] Getting file of media item 24
2011-10-31 13:09:19,187 DEBUG [VideoDAOImpl] Reading a Video (id = 24)
2011-10-31 13:09:19,187 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 24 and profile Panasonic DMP-BDT110
2011-10-31 13:09:19,187 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file 10,000 BC[2008]R5 DvDrip AC3[Eng]-FXG.avi: MPEG_PS_PAL
2011-10-31 13:09:19,187 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file 10,000 BC[2008]R5 DvDrip AC3[Eng]-FXG.avi: MPEG_PS_NTSC
2011-10-31 13:09:19,187 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 24)
2011-10-31 13:09:19,187 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-10-31 13:09:19,203 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_resetafterplay), returning it
2011-10-31 13:09:19,203 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-10-31 13:09:19,843 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-10-31 13:09:20,906 DEBUG [WebServer] Incoming connection from /192.168.0.12:1989
2011-10-31 13:09:20,906 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,HOST: 192.168.0.14:8895,transferMode.dlna.org: Streaming,getcontentFeatures.dlna.org: 1]]
2011-10-31 13:09:20,906 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:20,906 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:20,906 DEBUG [ResourceTransportRequestHandler] Request for resource 7 and type 'MEDIA_ITEM' received
2011-10-31 13:09:20,906 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2011-10-31 13:09:20,906 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:20,906 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:20,906 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:20,906 DEBUG [VideoDeliveryEngine] Delivering file 'MovieA.avi' using transcoding
2011-10-31 13:09:20,906 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:20,906 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2011-10-31 13:09:20,906 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: D:\DivX\Movies\Kids\MovieA.avi
2011-10-31 13:09:20,906 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i D:\DivX\Movies\Kids\MovieA.avi -y -threads 2 -vcodec mpeg2video -sameq -r 24000/1001 -g 15 -copyts -acodec copy -map 0:0 -map 0:1 -sn -f vob C:\WINDOWS\TEMP\Serviio\transcoding-temp-7-MPEG2PS.stf
2011-10-31 13:09:21,406 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:21,406 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:21,406 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-10-31 13:09:21,421 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7) as read
2011-10-31 13:09:21,437 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 50000000000
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=0.0-6421.0/6421,Date: Mon, 31 Oct 2011 02:09:21 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2011-10-31 13:09:21,593 DEBUG [WebServer] Incoming connection from /192.168.0.12:1990
2011-10-31 13:09:21,593 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,HOST: 192.168.0.14:8895,transferMode.dlna.org: Streaming,getcontentFeatures.dlna.org: 1]]
2011-10-31 13:09:21,593 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Request for resource 7 and type 'MEDIA_ITEM' received
2011-10-31 13:09:21,609 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2011-10-31 13:09:21,609 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:21,609 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Delivering file 'MovieA.avi' using transcoding
2011-10-31 13:09:21,609 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:21,609 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-10-31 13:09:21,609 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7) as read
2011-10-31 13:09:21,609 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 50000000000
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=0.0-6421.0/6421,Date: Mon, 31 Oct 2011 02:09:21 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2011-10-31 13:09:22,562 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-10-31 13:09:33,343 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82566DM-2 Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.0.14, timeout = 0
2011-10-31 13:09:33,343 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 33a806e5-df8a-3086-bdee-a45ec476debd
2011-10-31 13:09:35,156 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:01:15
2011-10-31 13:09:37,046 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-10-31 13:09:42,687 DEBUG [WebServer] Incoming connection from /192.168.0.12:1991
2011-10-31 13:09:42,687 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=10.079-,HOST: 192.168.0.14:8895,transferMode.dlna.org: Streaming,getcontentFeatures.dlna.org: 1]]
2011-10-31 13:09:42,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:42,687 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:42,687 DEBUG [ResourceTransportRequestHandler] Request for resource 7 and type 'MEDIA_ITEM' received
2011-10-31 13:09:42,687 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2011-10-31 13:09:42,687 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:42,687 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Delivering file MovieA.avi' using transcoding
2011-10-31 13:09:42,687 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:42,687 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 1743397815
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=10.079-6421.0/6421,Date: Mon, 31 Oct 2011 02:09:43 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2011-10-31 13:09:54,718 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-10-31 13:09:59,718 DEBUG [WebServer] Incoming connection from /192.168.0.12:1992
2011-10-31 13:09:59,718 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent unsubscription request received for service ContentDirectory and subscription uuid:df799be1-cf91-48f0-bd30-ffc8a3d121d6
2011-10-31 13:09:59,718 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription (uuid=df799be1-cf91-48f0-bd30-ffc8a3d121d6) removed for service urn:upnp-org:serviceId:ContentDirectory
2011-10-31 13:10:00,265 DEBUG [WebServer] Incoming connection from /192.168.0.12:1993
2011-10-31 13:10:00,265 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent unsubscription request received for service ContentDirectory and subscription uuid:df799be1-cf91-48f0-bd30-ffc8a3d121d6
2011-10-31 13:10:13,203 DEBUG [WebServer] I/O error: Connection reset
2011-10-31 13:10:19,843 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-10-31 13:10:50,765 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82566DM-2 Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.0.14, timeout = 0
2011-10-31 13:10:50,765 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 33a806e5-df8a-3086-bdee-a45ec476debd
2011-10-31 13:10:52,578 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:01:25
			
            I have a panasonic blue-ray player DMP-BDT110 and I'm trying to get it working using Serviio. I found it easy to install and I have it working.
To get it working I created the profile from this item - and it seemed to work! Fantasic
viewtopic.php?f=13&t=1218&start=40
I was able to browse my library of files and play a file. It played well with no issues until I tried to pause or fast forward it.
When I did this, it change the aspect ratio to a 4:3 (old small format) from the wide screen one 16:9, and the playback stopped. No matter what I did, I couldn't get it to restart (i.e. pressed play, etc)
I'm running on XP also.
I turned on debug and this is what I saw, I've tried to put in the log around the time of selecting MovieA.avi.
thanks,
MSJ
2011-10-31 13:09:14,781 INFO [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2011-10-31 13:09:14,796 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-10-31 13:09:14,796 DEBUG [EventDispatcher] Sending event notification #0 for subscription df799be1-cf91-48f0-bd30-ffc8a3d121d6 to endpoint http://192.168.0.12:60605/event_0
2011-10-31 13:09:14,796 DEBUG [RequestExecutor] Sending HTTP request to 192.168.0.12:60605
2011-10-31 13:09:14,796 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-10-31 13:09:14,796 DEBUG [EventDispatcher] Event notification sent and received successfully
2011-10-31 13:09:14,796 DEBUG [EventDispatcher] Sending event notification #1 for subscription dd7d642b-da17-4fdf-9b8b-04fd858cdb4d to endpoint http://192.168.0.10:2869/upnp/eventing/ssfbvdnajb
2011-10-31 13:09:14,796 DEBUG [RequestExecutor] Sending HTTP request to 192.168.0.10:2869
2011-10-31 13:09:14,812 DEBUG [EventDispatcher] Event notification sent and received successfully
2011-10-31 13:09:16,687 DEBUG [WebServer] Incoming connection from /192.168.0.12:1987
2011-10-31 13:09:16,687 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.12:
2011-10-31 13:09:16,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:16,687 DEBUG [ContentDirectory] Browse() called for renderer Unrecognized device (profile 21) with parameters: objectID = V, browseFlag = BrowseDirectChildren, filter = @childCount,dc:date,dc:creator,upnp:channelNr,upnp:channelName,upnp:genre,upnp:album,res,res@duration,res@size,res@bitrate,res@dlna:ifoFileURI,res@protocolInfo, startIndex = 0, count = 5, sortCriteria =
2011-10-31 13:09:16,703 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-10-31 13:09:16,718 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-10-31 13:09:16,734 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-10-31 13:09:16,734 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-10-31 13:09:16,750 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-10-31 13:09:16,750 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-10-31 13:09:16,750 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-10-31 13:09:19,125 DEBUG [WebServer] Incoming connection from /192.168.0.12:1988
2011-10-31 13:09:19,125 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.12:
2011-10-31 13:09:19,125 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:19,125 DEBUG [ContentDirectory] Browse() called for renderer Unrecognized device (profile 21) with parameters: objectID = V_LV, browseFlag = BrowseDirectChildren, filter = @childCount,dc:date,dc:creator,upnp:channelNr,upnp:channelName,upnp:genre,upnp:album,res,res@duration,res@size,res@bitrate,res@dlna:ifoFileURI,res@protocolInfo, startIndex = 0, count = 5, sortCriteria =
2011-10-31 13:09:19,125 DEBUG [VideoDAOImpl] Retrieving list of 10 last viewed videos (from=0, count=5)
2011-10-31 13:09:19,140 DEBUG [GenreDAOImpl] Reading a Genre (id = 6)
2011-10-31 13:09:19,156 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:19,156 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2011-10-31 13:09:19,156 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:19,171 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7 and profile Panasonic DMP-BDT110
2011-10-31 13:09:19,171 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:19,171 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:19,171 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 7)
2011-10-31 13:09:19,187 DEBUG [GenreDAOImpl] Reading a Genre (id = 4)
2011-10-31 13:09:19,187 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 24)
2011-10-31 13:09:19,187 DEBUG [MediaItemDAOImpl] Getting file of media item 24
2011-10-31 13:09:19,187 DEBUG [VideoDAOImpl] Reading a Video (id = 24)
2011-10-31 13:09:19,187 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 24 and profile Panasonic DMP-BDT110
2011-10-31 13:09:19,187 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file 10,000 BC[2008]R5 DvDrip AC3[Eng]-FXG.avi: MPEG_PS_PAL
2011-10-31 13:09:19,187 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file 10,000 BC[2008]R5 DvDrip AC3[Eng]-FXG.avi: MPEG_PS_NTSC
2011-10-31 13:09:19,187 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 24)
2011-10-31 13:09:19,187 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-10-31 13:09:19,203 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_resetafterplay), returning it
2011-10-31 13:09:19,203 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-10-31 13:09:19,843 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-10-31 13:09:20,906 DEBUG [WebServer] Incoming connection from /192.168.0.12:1989
2011-10-31 13:09:20,906 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,HOST: 192.168.0.14:8895,transferMode.dlna.org: Streaming,getcontentFeatures.dlna.org: 1]]
2011-10-31 13:09:20,906 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:20,906 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:20,906 DEBUG [ResourceTransportRequestHandler] Request for resource 7 and type 'MEDIA_ITEM' received
2011-10-31 13:09:20,906 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2011-10-31 13:09:20,906 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:20,906 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:20,906 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:20,906 DEBUG [VideoDeliveryEngine] Delivering file 'MovieA.avi' using transcoding
2011-10-31 13:09:20,906 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:20,906 DEBUG [MediaItemDAOImpl] Getting file of media item 7
2011-10-31 13:09:20,906 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: D:\DivX\Movies\Kids\MovieA.avi
2011-10-31 13:09:20,906 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i D:\DivX\Movies\Kids\MovieA.avi -y -threads 2 -vcodec mpeg2video -sameq -r 24000/1001 -g 15 -copyts -acodec copy -map 0:0 -map 0:1 -sn -f vob C:\WINDOWS\TEMP\Serviio\transcoding-temp-7-MPEG2PS.stf
2011-10-31 13:09:21,406 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:21,406 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:21,406 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-10-31 13:09:21,421 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7) as read
2011-10-31 13:09:21,437 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 50000000000
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-10-31 13:09:21,437 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=0.0-6421.0/6421,Date: Mon, 31 Oct 2011 02:09:21 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2011-10-31 13:09:21,593 DEBUG [WebServer] Incoming connection from /192.168.0.12:1990
2011-10-31 13:09:21,593 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,HOST: 192.168.0.14:8895,transferMode.dlna.org: Streaming,getcontentFeatures.dlna.org: 1]]
2011-10-31 13:09:21,593 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Request for resource 7 and type 'MEDIA_ITEM' received
2011-10-31 13:09:21,609 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2011-10-31 13:09:21,609 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:21,609 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Delivering file 'MovieA.avi' using transcoding
2011-10-31 13:09:21,609 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:21,609 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:21,609 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-10-31 13:09:21,609 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7) as read
2011-10-31 13:09:21,609 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 50000000000
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-10-31 13:09:21,609 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=0.0-6421.0/6421,Date: Mon, 31 Oct 2011 02:09:21 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2011-10-31 13:09:22,562 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-10-31 13:09:33,343 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82566DM-2 Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.0.14, timeout = 0
2011-10-31 13:09:33,343 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 33a806e5-df8a-3086-bdee-a45ec476debd
2011-10-31 13:09:35,156 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:01:15
2011-10-31 13:09:37,046 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-10-31 13:09:42,687 DEBUG [WebServer] Incoming connection from /192.168.0.12:1991
2011-10-31 13:09:42,687 DEBUG [ResourceTransportRequestHandler] GET /resource/7/MEDIA_ITEM/MPEG_PS_PAL$0 HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=10.079-,HOST: 192.168.0.14:8895,transferMode.dlna.org: Streaming,getcontentFeatures.dlna.org: 1]]
2011-10-31 13:09:42,687 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.12
2011-10-31 13:09:42,687 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:42,687 DEBUG [ResourceTransportRequestHandler] Request for resource 7 and type 'MEDIA_ITEM' received
2011-10-31 13:09:42,687 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7 (local)
2011-10-31 13:09:42,687 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7)
2011-10-31 13:09:42,687 DEBUG [VideoDAOImpl] Reading a Video (id = 7)
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Delivering item '7' for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Delivering file MovieA.avi' using transcoding
2011-10-31 13:09:42,687 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.0.12, Profile=Panasonic DMP-BDT110'
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_PAL
2011-10-31 13:09:42,687 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file MovieA.avi: MPEG_PS_NTSC
2011-10-31 13:09:42,687 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 1743397815
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-10-31 13:09:43,000 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=10.079-6421.0/6421,Date: Mon, 31 Oct 2011 02:09:43 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2011-10-31 13:09:54,718 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-10-31 13:09:59,718 DEBUG [WebServer] Incoming connection from /192.168.0.12:1992
2011-10-31 13:09:59,718 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent unsubscription request received for service ContentDirectory and subscription uuid:df799be1-cf91-48f0-bd30-ffc8a3d121d6
2011-10-31 13:09:59,718 DEBUG [ServiceEventSubscriptionRequestHandler] Event subscription (uuid=df799be1-cf91-48f0-bd30-ffc8a3d121d6) removed for service urn:upnp-org:serviceId:ContentDirectory
2011-10-31 13:10:00,265 DEBUG [WebServer] Incoming connection from /192.168.0.12:1993
2011-10-31 13:10:00,265 DEBUG [ServiceEventSubscriptionRequestHandler] ServiceEvent unsubscription request received for service ContentDirectory and subscription uuid:df799be1-cf91-48f0-bd30-ffc8a3d121d6
2011-10-31 13:10:13,203 DEBUG [WebServer] I/O error: Connection reset
2011-10-31 13:10:19,843 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-10-31 13:10:50,765 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Intel(R) 82566DM-2 Gigabit Network Connection - Packet Scheduler Miniport) and address 192.168.0.14, timeout = 0
2011-10-31 13:10:50,765 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 33a806e5-df8a-3086-bdee-a45ec476debd
2011-10-31 13:10:52,578 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:01:25
