Sun May 11, 2014 10:33 am
Sony - Playback restarting from beginning of file
Hello
I have a sony BDP s370 and had been using Serviio 1.4.1.1 merrily to transcode and play files without any significant problems under windows XP. I recently upgraded to Windows 7 and Serviio 1.4.1.2 and since that time if I pause playback of any file for more than a minute or two when I resume playback it will play as normal for a short time before playback then skips back to the very beginning of the file.
This necessitates the playback to be stopped immediately, hoping that the player has remembered the position it was in the file, or if you didn't get to it quick enough, to fast forward back to the position in the file. This is particularly annoying as fast-forwarding on the transcoded files on this player can be a bit hairy, if it works at all.
The problem has even cropped up when playing a movie without pausing and it randomly skipped back to the start about 20 minutes from the end (ie transcoding had probably finished)
As I say this problem has only occurred with the very newest serviio and Windows 7. I have tried various "sleep" related solutions for windows as I thought the harddisk was going to sleep. But the computer is wide awake when playback resumes from the very beginning of the file.
I dont believe this is a specific transcoding issue and it is not a player issue as this did not happen under windows xp. For now as a workaround I can just not use pause and stop the files instead for the player to resume when play is pressed (which works absolutely fine, which would suggest not a player problem, its a serviio issue).
I have included a log of one instance of the error. According to my watch it happened at about 2014-05-11 11:04:51.
2014-05-11 11:01:25,836 DEBUG [RepositoryDAOImpl] Marking Repository 3 as scanned with current timestamp
2014-05-11 11:01:29,825 DEBUG [SearchManager] Committing search index
2014-05-11 11:01:51,507 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:01:51,507 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:01:56,010 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2014-05-11 11:01:59,827 DEBUG [SearchManager] Committing search index
2014-05-11 11:02:29,829 DEBUG [SearchManager] Committing search index
2014-05-11 11:02:51,508 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:02:51,508 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:02:59,831 DEBUG [SearchManager] Committing search index
2014-05-11 11:03:29,832 DEBUG [SearchManager] Committing search index
2014-05-11 11:03:40,414 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52968: GET /resource/5208/MEDIA_ITEM/AVC_TS_MP_HD_AAC_MULT5_ISO-1/ORIGINAL HTTP/1.1, headers = [Host: 192.168.0.11:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"]]
2014-05-11 11:03:40,414 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:03:40,415 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:03:40,415 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.5, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:03:40,415 DEBUG [ResourceDeliveryProcessor] Request for resource 5208 and type 'MEDIA_ITEM' received
2014-05-11 11:03:40,416 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 5208 (local)
2014-05-11 11:03:40,416 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 5208)
2014-05-11 11:03:40,416 DEBUG [VideoDAOImpl] Reading a Video (id = 5208)
2014-05-11 11:03:40,416 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 5208, format AVC_TS_MP_HD_AAC_MULT5_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2014-05-11 11:03:40,417 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4
2014-05-11 11:03:40,418 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4: AVC_TS_MP_HD_AAC_MULT5_ISO
2014-05-11 11:03:40,418 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 5208 (local)
2014-05-11 11:03:40,418 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 5208)
2014-05-11 11:03:40,418 DEBUG [VideoDAOImpl] Reading a Video (id = 5208)
2014-05-11 11:03:40,419 DEBUG [VideoDeliveryEngine] Delivering item '5208' for client 'Identifier=192.168.0.5, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:03:40,419 DEBUG [VideoDeliveryEngine] Delivering file 'Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4' using transcoding
2014-05-11 11:03:40,420 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.0.5, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:03:40,420 DEBUG [FileBasedTranscodingDeliveryStrategy] Transcoded file 'e:\TEMP\Serviio\transcoding-temp-5208-bdp2010-ORIGINAL.stf' is complete, sending simple stream
2014-05-11 11:03:40,420 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4: AVC_TS_MP_HD_AAC_MULT5_ISO
2014-05-11 11:03:40,421 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 5208) as read
2014-05-11 11:03:40,423 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2014-05-11 11:03:40,429 DEBUG [GETMethodProcessor] Stream entity has length: 1638484672
2014-05-11 11:03:40,430 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2014-05-11 11:03:40,430 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sun, 11 May 2014 10:03:40 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.4.1.2,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-6224/6224,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-05-11 11:03:51,510 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:03:51,510 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:03:59,834 DEBUG [SearchManager] Committing search index
2014-05-11 11:04:19,470 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.11, timeout = 0
2014-05-11 11:04:19,471 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 91e97f41-67af-3556-9b8f-4aa7316b17d4
2014-05-11 11:04:20,078 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35594: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:20,079 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,079 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.4 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";])
2014-05-11 11:04:20,079 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,080 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]
2014-05-11 11:04:20,080 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,084 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:20,141 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35595: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:20,142 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,142 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:20,143 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,192 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35596: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:20,193 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,193 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:20,195 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,243 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52969: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:20,243 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,244 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.5 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";])
2014-05-11 11:04:20,244 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,244 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]
2014-05-11 11:04:20,244 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,246 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:20,279 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35597: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:20,279 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,280 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.4
2014-05-11 11:04:20,280 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,331 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52970: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:20,331 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,331 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:20,332 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,494 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:20,505 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35598: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:20,506 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,506 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.4
2014-05-11 11:04:20,506 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,623 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52971: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:21 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:20,623 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,623 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:20,624 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,715 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:20,727 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,028 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52972: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:21,028 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,029 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.5
2014-05-11 11:04:21,029 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,212 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35599: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:21,212 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,213 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.4 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";])
2014-05-11 11:04:21,213 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,213 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]
2014-05-11 11:04:21,214 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,216 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:21,230 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,271 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:03:57 (advertisement duration is 1800 sec.)
2014-05-11 11:04:21,298 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35600: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:21,298 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,298 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:21,299 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:21,343 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52973: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:21,343 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,343 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.5
2014-05-11 11:04:21,343 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,344 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35601: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:21,344 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,344 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:21,344 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:21,428 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35602: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:21,428 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,429 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.4
2014-05-11 11:04:21,429 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,551 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:21,564 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,633 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,645 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35603: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:21,645 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,646 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.4
2014-05-11 11:04:21,646 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,847 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:21,854 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:22,644 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52974: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:22,644 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,645 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.5 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";])
2014-05-11 11:04:22,645 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,645 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]
2014-05-11 11:04:22,645 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,647 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:22,828 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52975: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:22,828 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,829 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:22,829 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:23,232 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52976: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:23,233 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,233 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:23,233 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:23,633 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52977: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:23,633 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,633 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.5
2014-05-11 11:04:23,633 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,841 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:23,952 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52978: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:23,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,953 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.5
2014-05-11 11:04:23,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:24,155 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:24,161 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:29,836 DEBUG [SearchManager] Committing search index
2014-05-11 11:04:51,511 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:04:51,511 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:04:51,573 DEBUG [PlaylistDAOImpl] Reading all Playlists
2014-05-11 11:04:52,735 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:52,736 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:52,737 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60882
2014-05-11 11:04:52,762 DEBUG [ServiioHttpService] Incoming request from /192.168.0.11:51768: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.11:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50]]
2014-05-11 11:04:52,762 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:52,763 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.11 (headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.11:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50])
2014-05-11 11:04:52,763 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:52,763 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.11:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50]
2014-05-11 11:04:52,764 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:52,775 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2014-05-11 11:04:55,735 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:55,737 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:55,738 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60882
2014-05-11 11:04:56,363 DEBUG [ServiioHttpService] Incoming request from /192.168.0.11:51783: GET /icon/smallPNG HTTP/1.1, headers = [Connection: Keep-Alive,User-Agent: Windows-Media-Player-DMS/12.0.7601.17514,Host: 192.168.0.11:8895]]
2014-05-11 11:04:56,363 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:56,363 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG
2014-05-11 11:04:58,735 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:58,737 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:58,737 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60882
2014-05-11 11:04:59,837 DEBUG [SearchManager] Committing search index
2014-05-11 11:05:00,821 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-05-11 11:05:19,470 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.11:60882s
2014-05-11 11:05:22,470 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.11:60882s
2014-05-11 11:05:25,470 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.11:60882s
2014-05-11 11:05:29,136 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.0.11:60970s
2014-05-11 11:05:29,142 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60970
2014-05-11 11:05:29,838 DEBUG [SearchManager] Committing search index
2014-05-11 11:05:31,161 DEBUG [ServiioHttpService] Incoming request from /192.168.0.11:51821: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Connection: close]]
2014-05-11 11:05:31,162 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:05:31,162 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.11 (headers = [Host: 192.168.0.11:8895,Connection: close])
2014-05-11 11:05:31,162 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:05:31,163 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Connection: close]
2014-05-11 11:05:31,163 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:05:31,166 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2014-05-11 11:05:51,511 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:05:51,511 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:05:56,132 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-78843c4b7f2b from address 192.168.0.4
2014-05-11 11:05:56,453 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-78843c4b7f2b from address 192.168.0.4
2014-05-11 11:05:59,839 DEBUG [SearchManager] Committing search index
2014-05-11 11:06:01,739 DEBUG [WebServer] I/O error: Connection reset
2014-05-11 11:06:25,837 DEBUG [RepositoryDAOImpl] Reading all Repositories
I have a sony BDP s370 and had been using Serviio 1.4.1.1 merrily to transcode and play files without any significant problems under windows XP. I recently upgraded to Windows 7 and Serviio 1.4.1.2 and since that time if I pause playback of any file for more than a minute or two when I resume playback it will play as normal for a short time before playback then skips back to the very beginning of the file.
This necessitates the playback to be stopped immediately, hoping that the player has remembered the position it was in the file, or if you didn't get to it quick enough, to fast forward back to the position in the file. This is particularly annoying as fast-forwarding on the transcoded files on this player can be a bit hairy, if it works at all.
The problem has even cropped up when playing a movie without pausing and it randomly skipped back to the start about 20 minutes from the end (ie transcoding had probably finished)
As I say this problem has only occurred with the very newest serviio and Windows 7. I have tried various "sleep" related solutions for windows as I thought the harddisk was going to sleep. But the computer is wide awake when playback resumes from the very beginning of the file.
I dont believe this is a specific transcoding issue and it is not a player issue as this did not happen under windows xp. For now as a workaround I can just not use pause and stop the files instead for the player to resume when play is pressed (which works absolutely fine, which would suggest not a player problem, its a serviio issue).
I have included a log of one instance of the error. According to my watch it happened at about 2014-05-11 11:04:51.
2014-05-11 11:01:25,836 DEBUG [RepositoryDAOImpl] Marking Repository 3 as scanned with current timestamp
2014-05-11 11:01:29,825 DEBUG [SearchManager] Committing search index
2014-05-11 11:01:51,507 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:01:51,507 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:01:56,010 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2014-05-11 11:01:59,827 DEBUG [SearchManager] Committing search index
2014-05-11 11:02:29,829 DEBUG [SearchManager] Committing search index
2014-05-11 11:02:51,508 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:02:51,508 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:02:59,831 DEBUG [SearchManager] Committing search index
2014-05-11 11:03:29,832 DEBUG [SearchManager] Committing search index
2014-05-11 11:03:40,414 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52968: GET /resource/5208/MEDIA_ITEM/AVC_TS_MP_HD_AAC_MULT5_ISO-1/ORIGINAL HTTP/1.1, headers = [Host: 192.168.0.11:8895,Accept: */*,TimeSeekRange.dlna.org: npt=0-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player",X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0"]]
2014-05-11 11:03:40,414 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:03:40,415 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:03:40,415 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.0.5, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:03:40,415 DEBUG [ResourceDeliveryProcessor] Request for resource 5208 and type 'MEDIA_ITEM' received
2014-05-11 11:03:40,416 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 5208 (local)
2014-05-11 11:03:40,416 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 5208)
2014-05-11 11:03:40,416 DEBUG [VideoDAOImpl] Reading a Video (id = 5208)
2014-05-11 11:03:40,416 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 5208, format AVC_TS_MP_HD_AAC_MULT5_ISO and profile Sony BDP-Sx70/SMP-N100 (2010)
2014-05-11 11:03:40,417 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4
2014-05-11 11:03:40,418 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4: AVC_TS_MP_HD_AAC_MULT5_ISO
2014-05-11 11:03:40,418 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 5208 (local)
2014-05-11 11:03:40,418 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 5208)
2014-05-11 11:03:40,418 DEBUG [VideoDAOImpl] Reading a Video (id = 5208)
2014-05-11 11:03:40,419 DEBUG [VideoDeliveryEngine] Delivering item '5208' for client 'Identifier=192.168.0.5, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:03:40,419 DEBUG [VideoDeliveryEngine] Delivering file 'Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4' using transcoding
2014-05-11 11:03:40,420 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.0.5, Profile=Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:03:40,420 DEBUG [FileBasedTranscodingDeliveryStrategy] Transcoded file 'e:\TEMP\Serviio\transcoding-temp-5208-bdp2010-ORIGINAL.stf' is complete, sending simple stream
2014-05-11 11:03:40,420 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bulletproof.Monk.2003.1080p.BrRip.x264.YIFY.mp4: AVC_TS_MP_HD_AAC_MULT5_ISO
2014-05-11 11:03:40,421 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 5208) as read
2014-05-11 11:03:40,423 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2014-05-11 11:03:40,429 DEBUG [GETMethodProcessor] Stream entity has length: 1638484672
2014-05-11 11:03:40,430 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2014-05-11 11:03:40,430 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/vnd.dlna.mpeg-tts,Date: Sun, 11 May 2014 10:03:40 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/1.4.1.2,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-6224/6224,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-05-11 11:03:51,510 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:03:51,510 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:03:59,834 DEBUG [SearchManager] Committing search index
2014-05-11 11:04:19,470 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth3 (Realtek PCIe GBE Family Controller) and address 192.168.0.11, timeout = 0
2014-05-11 11:04:19,471 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 91e97f41-67af-3556-9b8f-4aa7316b17d4
2014-05-11 11:04:20,078 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35594: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:20,079 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,079 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.4 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";])
2014-05-11 11:04:20,079 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,080 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]
2014-05-11 11:04:20,080 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,084 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:20,141 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35595: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:20,142 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,142 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:20,143 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,192 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35596: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:19 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:20,193 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,193 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:20,195 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,243 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52969: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:20,243 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,244 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.5 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";])
2014-05-11 11:04:20,244 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,244 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]
2014-05-11 11:04:20,244 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,246 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:20,279 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35597: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:20,279 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,280 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.4
2014-05-11 11:04:20,280 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,331 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52970: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:20,331 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,331 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:20,332 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,494 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:20,505 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35598: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:20,506 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,506 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.4
2014-05-11 11:04:20,506 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:20,623 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52971: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:21 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:20,623 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:20,623 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:20,624 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:20,715 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:20,727 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,028 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52972: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:21,028 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,029 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.5
2014-05-11 11:04:21,029 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,212 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35599: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:21,212 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,213 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.4 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";])
2014-05-11 11:04:21,213 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,213 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]
2014-05-11 11:04:21,214 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,216 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:21,230 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,271 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:03:57 (advertisement duration is 1800 sec.)
2014-05-11 11:04:21,298 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35600: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:21,298 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,298 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:21,299 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:21,343 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52973: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:21,343 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,343 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.5
2014-05-11 11:04:21,343 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:21,344 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35601: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";]]
2014-05-11 11:04:21,344 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,344 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:21,344 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:21,428 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35602: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:21,428 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,429 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.4
2014-05-11 11:04:21,429 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,551 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:21,564 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,633 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:21,645 DEBUG [ServiioHttpService] Incoming request from /192.168.0.4:35603: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="BRAVIA KDL-40EX503";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-40EX503"; mv="1.7";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:21,645 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,646 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.4
2014-05-11 11:04:21,646 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.4
2014-05-11 11:04:21,847 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2014-05-11 11:04:21,854 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:22,644 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52974: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:22,644 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,645 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.5 (headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";])
2014-05-11 11:04:22,645 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,645 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]
2014-05-11 11:04:22,645 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,647 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:22,828 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52975: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:22,828 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:22,829 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2014-05-11 11:04:22,829 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:23,232 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52976: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.0.11:8895,Date: Sun, 11 May 2014 10:04:23 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";]]
2014-05-11 11:04:23,233 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,233 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2014-05-11 11:04:23,233 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2014-05-11 11:04:23,633 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52977: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 280,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"]]
2014-05-11 11:04:23,633 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,633 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.5
2014-05-11 11:04:23,633 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,841 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:23,952 DEBUG [ServiioHttpService] Incoming request from /192.168.0.5:52978: POST /serviceControl HTTP/1.1, headers = [X-AV-Physical-Unit-Info: pa="Blu-ray Disc Player";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="Blu-ray Disc Player"; mv="2.0";,Host: 192.168.0.11:8895,User-Agent: UPnP/1.0 DLNADOC/1.50,Content-Length: 273,Content-Type: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"]]
2014-05-11 11:04:23,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:23,953 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.5
2014-05-11 11:04:23,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.5
2014-05-11 11:04:24,155 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony BDP-Sx70/SMP-N100 (2010)'
2014-05-11 11:04:24,161 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2014-05-11 11:04:29,836 DEBUG [SearchManager] Committing search index
2014-05-11 11:04:51,511 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:04:51,511 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:04:51,573 DEBUG [PlaylistDAOImpl] Reading all Playlists
2014-05-11 11:04:52,735 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:52,736 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:52,737 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60882
2014-05-11 11:04:52,762 DEBUG [ServiioHttpService] Incoming request from /192.168.0.11:51768: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.11:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50]]
2014-05-11 11:04:52,762 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:52,763 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.11 (headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.11:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50])
2014-05-11 11:04:52,763 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:52,763 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.11:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50]
2014-05-11 11:04:52,764 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:52,775 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2014-05-11 11:04:55,735 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:55,737 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:55,738 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60882
2014-05-11 11:04:56,363 DEBUG [ServiioHttpService] Incoming request from /192.168.0.11:51783: GET /icon/smallPNG HTTP/1.1, headers = [Connection: Keep-Alive,User-Agent: Windows-Media-Player-DMS/12.0.7601.17514,Host: 192.168.0.11:8895]]
2014-05-11 11:04:56,363 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:04:56,363 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG
2014-05-11 11:04:58,735 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:58,737 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.11:60882s
2014-05-11 11:04:58,737 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60882
2014-05-11 11:04:59,837 DEBUG [SearchManager] Committing search index
2014-05-11 11:05:00,821 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2014-05-11 11:05:19,470 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.11:60882s
2014-05-11 11:05:22,470 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.11:60882s
2014-05-11 11:05:25,470 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.11:60882s
2014-05-11 11:05:29,136 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target upnp:rootdevice from address /192.168.0.11:60970s
2014-05-11 11:05:29,142 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.11:60970
2014-05-11 11:05:29,838 DEBUG [SearchManager] Committing search index
2014-05-11 11:05:31,161 DEBUG [ServiioHttpService] Incoming request from /192.168.0.11:51821: GET /deviceDescription/91e97f41-67af-3556-9b8f-4aa7316b17d4 HTTP/1.1, headers = [Host: 192.168.0.11:8895,Connection: close]]
2014-05-11 11:05:31,162 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:05:31,162 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 91e97f41-67af-3556-9b8f-4aa7316b17d4 from 192.168.0.11 (headers = [Host: 192.168.0.11:8895,Connection: close])
2014-05-11 11:05:31,162 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:05:31,163 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.11:8895,Connection: close]
2014-05-11 11:05:31,163 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.11
2014-05-11 11:05:31,166 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2014-05-11 11:05:51,511 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2014-05-11 11:05:51,511 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2014-05-11 11:05:56,132 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-78843c4b7f2b from address 192.168.0.4
2014-05-11 11:05:56,453 DEBUG [DiscoverySSDPMessageListener] Received a valid NOTIFY (ssdp:alive) message from Renderer 00000000-0000-1010-8000-78843c4b7f2b from address 192.168.0.4
2014-05-11 11:05:59,839 DEBUG [SearchManager] Committing search index
2014-05-11 11:06:01,739 DEBUG [WebServer] I/O error: Connection reset
2014-05-11 11:06:25,837 DEBUG [RepositoryDAOImpl] Reading all Repositories