Wed Feb 26, 2014 5:26 pm by Smitty
I just realized that on the Sony Bravia was displayed AVC as the file format of the mp4-file instead of mpeg2 (to which I thought it was transcoded).
Here are the lines of the log which I think do describe the request of the Sony Bravia (not sure if I got everything out of the 60.000 lines):
2014-02-26 17:43:45,015 DEBUG [ServiioHttpService] Incoming request from /192.168.123.107:55905: HEAD /resource/7859/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO-2/ORIGINAL HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX701";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX701"; mv="1.7";,Host: 192.168.123.100:8895]]
2014-02-26 17:43:45,015 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.107
2014-02-26 17:43:45,015 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.107
2014-02-26 17:43:45,015 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:45,015 DEBUG [ResourceDeliveryProcessor] Request for resource 7859 and type 'MEDIA_ITEM' received
2014-02-26 17:43:45,015 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7859 (local)
2014-02-26 17:43:45,015 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7859)
2014-02-26 17:43:45,015 DEBUG [VideoDAOImpl] Reading a Video (id = 7859)
2014-02-26 17:43:45,015 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7859, format AVC_TS_MP_SD_AC3_ISO and profile Sony Bravia TV (2010)
2014-02-26 17:43:45,015 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4
2014-02-26 17:43:45,015 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4: AVC_TS_MP_SD_AC3_ISO
2014-02-26 17:43:45,015 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Wed, 26 Feb 2014 16:43:45 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.4,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=AVC_TS_HD_60_AC3_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-02-26 17:43:45,046 DEBUG [ServiioHttpService] Incoming request from /192.168.123.107:55906: GET /resource/7859/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO-2/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX701";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX701"; mv="1.7";,Host: 192.168.123.100:8895]]
2014-02-26 17:43:45,046 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.107
2014-02-26 17:43:45,046 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.107
2014-02-26 17:43:45,046 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:45,046 DEBUG [ResourceDeliveryProcessor] Request for resource 7859 and type 'MEDIA_ITEM' received
2014-02-26 17:43:45,046 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7859 (local)
2014-02-26 17:43:45,046 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7859)
2014-02-26 17:43:45,046 DEBUG [VideoDAOImpl] Reading a Video (id = 7859)
2014-02-26 17:43:45,046 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7859, format AVC_TS_MP_SD_AC3_ISO and profile Sony Bravia TV (2010)
2014-02-26 17:43:45,046 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4
2014-02-26 17:43:45,046 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4: AVC_TS_MP_SD_AC3_ISO
2014-02-26 17:43:45,046 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7859 (local)
2014-02-26 17:43:45,046 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7859)
2014-02-26 17:43:45,046 DEBUG [VideoDAOImpl] Reading a Video (id = 7859)
2014-02-26 17:43:45,046 DEBUG [VideoDeliveryEngine] Delivering item '7859' for client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:45,046 DEBUG [VideoDeliveryEngine] Delivering file 'Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4' using transcoding
2014-02-26 17:43:45,046 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:45,046 DEBUG [MediaItemDAOImpl] Getting file of media item 7859
2014-02-26 17:43:45,046 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: E:\Medien\Video\Krimis\Crossing Jordan\Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4
2014-02-26 17:43:45,046 DEBUG [ProcessExecutor] Starting C:\Programme\Serviio\bin\\..\lib/ffmpeg.exe -fflags +genpts -threads auto -i E:\Medien\Video\Krimis\Crossing Jordan\Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4 -y -threads auto -c:v copy -bsf:v h264_mp4toannexb -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts C:\WINDOWS\TEMP\Serviio\transcoding-temp-7859-sony2010-ORIGINAL.stf
2014-02-26 17:43:45,546 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2014-02-26 17:43:45,546 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4: AVC_TS_MP_SD_AC3_ISO
2014-02-26 17:43:45,546 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7859) as read
2014-02-26 17:43:45,562 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2014-02-26 17:43:45,562 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2014-02-26 17:43:45,562 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2014-02-26 17:43:45,562 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Wed, 26 Feb 2014 16:43:45 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.4,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-2489/2489,contentFeatures.dlna.org: DLNA.ORG_PN=AVC_TS_HD_60_AC3_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]
2014-02-26 17:43:45,812 DEBUG [SearchManager] Committing search index
2014-02-26 17:43:47,937 DEBUG [WebServer] I/O error: Software caused connection abort: socket write error
2014-02-26 17:43:47,953 DEBUG [ServiioHttpService] Incoming request from /192.168.123.107:55908: GET /resource/7859/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO-2/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX701";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX701"; mv="1.7";,Host: 192.168.123.100:8895]]
2014-02-26 17:43:47,953 DEBUG [RendererDAOImpl] Reading a Rendersgml3comer with ip address 192.168.123.107
2014-02-26 17:43:47,953 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.107
2014-02-26 17:43:47,953 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:47,953 DEBUG [ResourceDeliveryProcessor] Request for resource 7859 and type 'MEDIA_ITEM' received
2014-02-26 17:43:47,953 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7859 (local)
2014-02-26 17:43:47,953 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7859)
2014-02-26 17:43:47,953 DEBUG [VideoDAOImpl] Reading a Video (id = 7859)
2014-02-26 17:43:47,953 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 7859, format AVC_TS_MP_SD_AC3_ISO and profile Sony Bravia TV (2010)
2014-02-26 17:43:47,953 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4
2014-02-26 17:43:47,953 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4: AVC_TS_MP_SD_AC3_ISO
2014-02-26 17:43:47,953 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 7859 (local)
2014-02-26 17:43:47,953 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 7859)
2014-02-26 17:43:47,953 DEBUG [VideoDAOImpl] Reading a Video (id = 7859)
2014-02-26 17:43:47,953 DEBUG [VideoDeliveryEngine] Delivering item '7859' for client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:47,953 DEBUG [VideoDeliveryEngine] Delivering file 'Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4' using transcoding
2014-02-26 17:43:47,953 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'Identifier=192.168.123.107, Profile=Sony Bravia TV (2010)'
2014-02-26 17:43:47,953 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2014-02-26 17:43:47,953 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Crossing_Jordan_Pathologin_mit_Profil_In_der_Hitze_der_Nacht_Folge72_2014-02-19_2305_535854.mp4: AVC_TS_MP_SD_AC3_ISO
2014-02-26 17:43:47,953 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 7859) as read
2014-02-26 17:43:47,953 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2014-02-26 17:43:47,953 DEBUG [GETMethodProcessor] Stream entity has length: 50000000000
2014-02-26 17:43:47,953 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2014-02-26 17:43:47,953 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Wed, 26 Feb 2014 16:43:47 GMT,Server: Windows XP, UPnP/1.0 DLNADOC/1.50, Serviio/1.4,Cache-control: no-cache,TimeSeekRange.dlna.org: npt=0-2489/2489,contentFeatures.dlna.org: DLNA.ORG_PN=AVC_TS_HD_60_AC3_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*]]