[SOLVED] Tv cannot access server, blu-ray can

Hi,

I have version 1.0.1 installed on a Ubuntu 12.04 headless server.
I have a Sony Bravia KDL-46EX700 and a Sony BDP-S370 Blu-ray player. Both are connected by LAN to the same router. Both are on the latest firmware.

I can access the serviio server with my Blu-Ray but not with the TV. And with the blu-ray, everything is working (audio, pictures, video).
The server is listed in the Audio, Picture and Video section of the TV but when I choose it I always receive a message "cannot access server".
When I use the "Media Server Troubleshooting" of the TV, the serviio server doesn't show up in the list.

I can access the server from my iPod touch as well.

Any ideas?

[Illico]

Could you enable detailed serviio logging (see signature link) ?
- only power-on your TV
- delete serviio.log
- restart serviio service
- post servio.log here

I did those steps.

Here's the log:
2012-08-23 18:34:59,637 DEBUG [ApplicationInstanceManager] Port is already taken. Notifying first instance.
2012-08-23 18:34:59,643 DEBUG [ApplicationInstanceManager] Successfully notified first instance.

Even when I try to reach the server from the TV, nothing more is logged.


Something is strange though, in the console, I see that my tv is detected two times:

192.168.1.14 KDL-46EX700 Sony Bravia TV (2010) <= this one has a red dot
192.168.1.14 Unrecognized device Generic DLNA Profile <= this one has a yellow dot

Thanks.

Hi, I made some progress. I remove serviio and reinstalled it. I enabled detailed logging. Here's what I found:

  Code:

2012-08-25 12:41:28,152 DEBUG [WebServer] Incoming connection from /192.168.1.14:60226
2012-08-25 12:41:28,153 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 12:41:28,153 DEBUG [ResourceTransportRequestHandler] HEAD /resource/6/MEDIA_ITEM/MPEG_TS_SD_NA_ISO-0/ORIGINAL HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";,Host: 192.168.1.99:8895]]
2012-08-25 12:41:28,153 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 12:41:28,154 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 12:41:28,155 DEBUG [ResourceDeliveryProcessor] Request for resource 6 and type 'MEDIA_ITEM' received
2012-08-25 12:41:28,155 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 6 (local)
2012-08-25 12:41:28,155 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 6)
2012-08-25 12:41:28,155 DEBUG [VideoDAOImpl] Reading a Video (id = 6)
2012-08-25 12:41:28,157 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 6, format MPEG_TS_SD_NA_ISO and profile Sony Bravia TV (2010)
2012-08-25 12:41:28,157 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file movie.avi
2012-08-25 12:41:28,157 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_EU_ISO
2012-08-25 12:41:28,157 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_NA_ISO
2012-08-25 12:41:28,157 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_KO_ISO
2012-08-25 12:41:28,158 DEBUG [ResourceDeliveryProcessor] Sending HEAD response back
2012-08-25 12:41:28,158 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Sat, 25 Aug 2012 16:41:28 GMT,Server: Linux, UPnP/1.0 DLNADOC/1.50, Serviio/1.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2012-08-25 12:41:28,166 DEBUG [WebServer] Incoming connection from /192.168.1.14:60227
2012-08-25 12:41:28,167 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 12:41:28,168 DEBUG [ResourceTransportRequestHandler] GET /resource/6/MEDIA_ITEM/MPEG_TS_SD_NA_ISO-0/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";,Host: 192.168.1.99:8895]]
2012-08-25 12:41:28,168 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 12:41:28,169 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 12:41:28,169 DEBUG [ResourceDeliveryProcessor] Request for resource 6 and type 'MEDIA_ITEM' received
2012-08-25 12:41:28,169 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 6 (local)
2012-08-25 12:41:28,169 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 6)
2012-08-25 12:41:28,170 DEBUG [VideoDAOImpl] Reading a Video (id = 6)
2012-08-25 12:41:28,171 DEBUG [VideoDeliveryEngine] Delivering item '6' for client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 12:41:28,171 DEBUG [VideoDeliveryEngine] Delivering file 'movie.avi' using transcoding
2012-08-25 12:41:28,172 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 12:41:28,172 DEBUG [MediaItemDAOImpl] Getting file of media item 6
2012-08-25 12:41:28,173 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /home/media/video/movie.avi
2012-08-25 12:41:28,174 DEBUG [ProcessExecutor] Starting ffmpeg -i /home/media/video/movie.avi -y -threads 2 -copyts -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -r 30000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts /tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf
2012-08-25 12:41:28,247 WARN  [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2012-08-25 12:41:28,248 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
2012-08-25 12:41:30,339 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2012-08-25 12:41:35,345 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2012-08-25 12:41:35,678 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Transcoded file '/tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
java.io.IOException: Transcoded file '/tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf' cannot be found, FFmpeg execution probably failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:45)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:125)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:95)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:120)
   at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:84)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:79)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:191)
2012-08-25 12:41:38,802 DEBUG [WebServer] Incoming connection from /192.168.1.14:60228
2012-08-25 12:41:38,812 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 12:41:38,813 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 12:41:38,813 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 12:41:39,009 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2012-08-25 12:41:39,009 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 12:41:39,010 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2012-08-25 12:41:39,012 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 12:41:39,529 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2012-08-25 12:41:39,529 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-08-25 12:41:40,311 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2012-08-25 12:41:40,869 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.1.99, timeout = 0
2012-08-25 12:41:40,870 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device d6dd74bd-7013-3e09-9a39-c83cb088d508


Ouput of the ffmpeg command:

  Code:

ffmpeg -i /home/media/video/movie.avi -y -threads 2 -copyts -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -r 30000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts /tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf
ffmpeg version 0.8.3-4:0.8.3-0ubuntu0.12.04.1, Copyright (c) 2000-2012 the Libav developers
  built on Jun 12 2012 16:52:09 with gcc 4.6.3
*** THIS PROGRAM IS DEPRECATED ***
This program is only provided for compatibility and will be removed in a future release. Please use avconv instead.
Input #0, avi, from '/home/media/video/movie.avi':
  Metadata:
    encoder         : MEncoder dev-CVS-051014-22:10-3.4.4
  Duration: 01:20:37.03, start: 0.000000, bitrate: 1050 kb/s
    Stream #0.0: Video: mpeg4 (Simple Profile), yuv420p, 512x288 [PAR 1:1 DAR 16:9], 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0.1: Audio: mp3, 44100 Hz, stereo, s16, 160 kb/s
Unrecognized option 'c:v'
Failed to set value 'mpeg2video' for option 'c:v'


So it seems that ffmpeg crash because of option c:v

What can I do about it?

[Illico]

Could you install latest ffmpeg version or this one?
check this

This release includes FFmpeg compiled using this source code, libRTMP 2.4 compiled using this source code and Lame MP3 Encoder (v. 3.98) compiled using this source code.

Ok, I installed the latest ffmpeg from source. I'm still getting this message from the TV:

The server is not accessible. Please try again later.

Here's the new serviio.log:

  Code:

2012-08-25 16:28:10,657 DEBUG [WebServer] Incoming connection from /192.168.1.14:40063
2012-08-25 16:28:10,658 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:10,659 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 16:28:10,659 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:10,662 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = 2, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 10, count = 10, sortCriteria =
2012-08-25 16:28:10,662 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 16:28:10,663 DEBUG [PlaylistDAOImpl] Retrieving number of playlists for VIDEO [No_Restriction]
2012-08-25 16:28:10,664 DEBUG [OnlineRepositoryDAOImpl] Retrieving list of [FEED, WEB_RESOURCE] OnlineRepositories for VIDEO [No_Restriction]
2012-08-25 16:28:10,665 DEBUG [OnlineRepositoryDAOImpl] Retrieving list of [LIVE_STREAM] OnlineRepositories for VIDEO [No_Restriction]
2012-08-25 16:28:10,668 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:12,929 DEBUG [WebServer] Incoming connection from /192.168.1.14:40064
2012-08-25 16:28:12,931 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:12,932 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 16:28:12,932 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:12,935 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2012-08-25 16:28:12,935 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 16:28:12,936 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2012-08-25 16:28:12,938 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:13,589 DEBUG [WebServer] Incoming connection from /192.168.1.14:40065
2012-08-25 16:28:13,590 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:13,591 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 16:28:13,591 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:13,594 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = V_F^FOL_R4, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2012-08-25 16:28:13,595 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 16:28:13,595 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2012-08-25 16:28:13,603 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:14,148 DEBUG [WebServer] Incoming connection from /192.168.1.14:40066
2012-08-25 16:28:14,149 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:14,150 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 16:28:14,150 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:14,155 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = V_F^FOL_R4, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2012-08-25 16:28:14,155 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 16:28:14,156 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2012-08-25 16:28:14,162 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:15,778 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.1.99, timeout = 0
2012-08-25 16:28:15,778 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device d6dd74bd-7013-3e09-9a39-c83cb088d508
2012-08-25 16:28:16,384 DEBUG [WebServer] Incoming connection from /192.168.1.14:40067
2012-08-25 16:28:16,385 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,386 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device d6dd74bd-7013-3e09-9a39-c83cb088d508 from 192.168.1.14 (headers = [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:16 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";])
2012-08-25 16:28:16,386 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,386 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:16 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";]
2012-08-25 16:28:16,387 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,388 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:16,440 DEBUG [WebServer] Incoming connection from /192.168.1.14:40068
2012-08-25 16:28:16,441 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,442 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2012-08-25 16:28:16,443 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:16,488 DEBUG [WebServer] Incoming connection from /192.168.1.14:40069
2012-08-25 16:28:16,488 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,489 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2012-08-25 16:28:16,490 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:16,967 DEBUG [WebServer] Incoming connection from /192.168.1.14:40070
2012-08-25 16:28:16,968 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,969 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.14:
2012-08-25 16:28:16,969 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,972 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:16,985 DEBUG [WebServer] Incoming connection from /192.168.1.14:40071
2012-08-25 16:28:16,986 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,987 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.14:
2012-08-25 16:28:16,987 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:16,989 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:16,992 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:17,332 DEBUG [WebServer] Incoming connection from /192.168.1.14:40072
2012-08-25 16:28:17,333 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:17,333 DEBUG [ResourceTransportRequestHandler] HEAD /resource/6/MEDIA_ITEM/MPEG_TS_SD_NA_ISO-0/ORIGINAL HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";,Host: 192.168.1.99:8895]]
2012-08-25 16:28:17,333 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:17,334 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:17,334 DEBUG [ResourceDeliveryProcessor] Request for resource 6 and type 'MEDIA_ITEM' received
2012-08-25 16:28:17,334 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 6 (local)
2012-08-25 16:28:17,334 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 6)
2012-08-25 16:28:17,335 DEBUG [VideoDAOImpl] Reading a Video (id = 6)
2012-08-25 16:28:17,336 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 6, format MPEG_TS_SD_NA_ISO and profile Sony Bravia TV (2010)
2012-08-25 16:28:17,336 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file movie.avi
2012-08-25 16:28:17,336 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_EU_ISO
2012-08-25 16:28:17,336 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_NA_ISO
2012-08-25 16:28:17,336 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_KO_ISO
2012-08-25 16:28:17,336 DEBUG [ResourceDeliveryProcessor] Sending HEAD response back
2012-08-25 16:28:17,336 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Sat, 25 Aug 2012 20:28:17 GMT,Server: Linux, UPnP/1.0 DLNADOC/1.50, Serviio/1.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2012-08-25 16:28:17,345 DEBUG [WebServer] Incoming connection from /192.168.1.14:40073
2012-08-25 16:28:17,346 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:17,346 DEBUG [ResourceTransportRequestHandler] GET /resource/6/MEDIA_ITEM/MPEG_TS_SD_NA_ISO-0/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";,Host: 192.168.1.99:8895]]
2012-08-25 16:28:17,346 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:17,347 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:17,347 DEBUG [ResourceDeliveryProcessor] Request for resource 6 and type 'MEDIA_ITEM' received
2012-08-25 16:28:17,347 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 6 (local)
2012-08-25 16:28:17,347 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 6)
2012-08-25 16:28:17,348 DEBUG [VideoDAOImpl] Reading a Video (id = 6)
2012-08-25 16:28:17,349 DEBUG [VideoDeliveryEngine] Delivering item '6' for client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:17,349 DEBUG [VideoDeliveryEngine] Delivering file 'movie.avi' using transcoding
2012-08-25 16:28:17,349 DEBUG [AbstractTranscodingDeliveryEngine] No other client uses transcoding job of file 'transcoding-temp-8-sony2010-ORIGINAL.stf', will stop the job
2012-08-25 16:28:17,349 DEBUG [AbstractTranscodingDeliveryEngine] Stopping previous transcoding job of file 'transcoding-temp-8-sony2010-ORIGINAL.stf'
2012-08-25 16:28:17,349 DEBUG [TranscodingJobListener] Deleted temp file '/tmp/Serviio/transcoding-temp-8-sony2010-ORIGINAL.stf': false
2012-08-25 16:28:17,349 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:17,349 DEBUG [MediaItemDAOImpl] Getting file of media item 6
2012-08-25 16:28:17,350 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /home/media/video/movie.avi
2012-08-25 16:28:17,350 DEBUG [ProcessExecutor] Starting ffmpeg -i /home/media/video/movie.avi -y -threads 2 -copyts -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -r 30000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts /tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf
2012-08-25 16:28:17,351 DEBUG [FileBasedTranscodingDeliveryStrategy] Sending transcoding stream
2012-08-25 16:28:17,357 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_EU_ISO
2012-08-25 16:28:17,358 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_NA_ISO
2012-08-25 16:28:17,358 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file movie.avi: MPEG_TS_SD_KO_ISO
2012-08-25 16:28:17,358 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 6) as read
2012-08-25 16:28:17,377 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2012-08-25 16:28:17,378 DEBUG [ResourceDeliveryProcessor] Stream entity has length: 50000000000
2012-08-25 16:28:17,378 DEBUG [ResourceDeliveryProcessor] Sending file back
2012-08-25 16:28:17,378 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2012-08-25 16:28:17,378 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,TimeSeekRange.dlna.org: npt=0.0-4837.0/4837,Date: Sat, 25 Aug 2012 20:28:17 GMT,Server: Linux, UPnP/1.0 DLNADOC/1.50, Serviio/1.0.1,Cache-control: no-cache,contentFeatures.dlna.org: DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=01500000000000000000000000000000,transferMode.dlna.org: Streaming]]
2012-08-25 16:28:17,429 WARN  [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2012-08-25 16:28:17,429 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
2012-08-25 16:28:17,582 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:36
2012-08-25 16:28:19,242 DEBUG [WebServer] I/O error: Broken pipe
2012-08-25 16:28:19,245 DEBUG [WebServer] Incoming connection from /192.168.1.14:40074
2012-08-25 16:28:19,246 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,246 DEBUG [ResourceTransportRequestHandler] GET /resource/6/MEDIA_ITEM/MPEG_TS_SD_NA_ISO-0/ORIGINAL HTTP/1.1, headers = [TimeSeekRange.dlna.org: npt=0.000-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";,Host: 192.168.1.99:8895]]
2012-08-25 16:28:19,247 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,247 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:19,247 DEBUG [ResourceDeliveryProcessor] Request for resource 6 and type 'MEDIA_ITEM' received
2012-08-25 16:28:19,247 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 6 (local)
2012-08-25 16:28:19,247 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 6)
2012-08-25 16:28:19,248 DEBUG [VideoDAOImpl] Reading a Video (id = 6)
2012-08-25 16:28:19,249 DEBUG [VideoDeliveryEngine] Delivering item '6' for client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:19,249 DEBUG [VideoDeliveryEngine] Delivering file 'movie.avi' using transcoding
2012-08-25 16:28:19,249 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.14, Profile=Sony Bravia TV (2010)'
2012-08-25 16:28:19,249 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: FFmpeg execution failed
java.io.IOException: FFmpeg execution failed
   at org.serviio.delivery.resource.transcode.FileBasedTranscodingDeliveryStrategy.createInputStream(FileBasedTranscodingDeliveryStrategy.java:47)
   at org.serviio.delivery.resource.transcode.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:125)
   at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:95)
   at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:120)
   at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:84)
   at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:79)
   at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
   at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
   at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:58)
   at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
   at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:191)
2012-08-25 16:28:19,742 DEBUG [WebServer] Incoming connection from /192.168.1.14:40075
2012-08-25 16:28:19,743 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,744 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device d6dd74bd-7013-3e09-9a39-c83cb088d508 from 192.168.1.14 (headers = [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";])
2012-08-25 16:28:19,744 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,744 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:20 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";]
2012-08-25 16:28:19,745 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,746 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:19,780 DEBUG [WebServer] Incoming connection from /192.168.1.14:40076
2012-08-25 16:28:19,781 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,782 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2012-08-25 16:28:19,782 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:19,820 DEBUG [WebServer] Incoming connection from /192.168.1.14:40077
2012-08-25 16:28:19,821 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,822 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2012-08-25 16:28:19,822 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:19,921 DEBUG [WebServer] Incoming connection from /192.168.1.14:40078
2012-08-25 16:28:19,922 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,923 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.14:
2012-08-25 16:28:19,923 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,927 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:19,942 DEBUG [WebServer] Incoming connection from /192.168.1.14:40079
2012-08-25 16:28:19,943 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,944 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.14:
2012-08-25 16:28:19,944 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:19,946 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:19,950 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:27,036 DEBUG [WebServer] Incoming connection from /192.168.1.14:40080
2012-08-25 16:28:27,037 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:27,040 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 16:28:27,040 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:27,052 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2012-08-25 16:28:27,052 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 16:28:27,053 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2012-08-25 16:28:27,055 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:27,795 DEBUG [WebServer] Incoming connection from /192.168.1.14:40081
2012-08-25 16:28:27,795 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:27,796 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.14:
2012-08-25 16:28:27,796 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:27,799 DEBUG [ContentDirectory] Browse() called for renderer KDL-46EX700 (profile sony2010) with parameters: objectID = 2, browseFlag = BrowseDirectChildren, filter = dc:title,av:mediaClass,dc:date,@childCount,res,upnp:class,res@resolution,upnp:album,upnp:genre,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID,dc:creator,res@size,res@duration,res@bitrate,res@protocolInfo, startIndex = 0, count = 10, sortCriteria =
2012-08-25 16:28:27,799 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2012-08-25 16:28:27,800 DEBUG [VideoDAOImpl] Retrieving number of video initials [No_Restriction]
2012-08-25 16:28:27,802 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2 [No_Restriction]
2012-08-25 16:28:27,802 DEBUG [SeriesDAOImpl] Retrieving number of series
2012-08-25 16:28:27,803 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO [No_Restriction]
2012-08-25 16:28:27,804 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos [No_Restriction]
2012-08-25 16:28:27,806 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2012-08-25 16:28:27,806 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0 [No_Restriction]
2012-08-25 16:28:27,808 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2012-08-25 16:28:27,809 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2012-08-25 16:28:27,810 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2012-08-25 16:28:27,811 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2012-08-25 16:28:27,818 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:41,026 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2012-08-25 16:28:41,026 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2012-08-25 16:28:54,302 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.1.99, timeout = 0
2012-08-25 16:28:54,302 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device d6dd74bd-7013-3e09-9a39-c83cb088d508
2012-08-25 16:28:54,909 DEBUG [WebServer] Incoming connection from /192.168.1.14:40087
2012-08-25 16:28:54,913 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:54,913 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device d6dd74bd-7013-3e09-9a39-c83cb088d508 from 192.168.1.14 (headers = [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:55 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";])
2012-08-25 16:28:54,913 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:54,914 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:55 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";]
2012-08-25 16:28:54,914 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:54,916 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:54,962 DEBUG [WebServer] Incoming connection from /192.168.1.14:40088
2012-08-25 16:28:54,963 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:54,964 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2012-08-25 16:28:54,964 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:55,005 DEBUG [WebServer] Incoming connection from /192.168.1.14:40089
2012-08-25 16:28:55,007 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,008 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2012-08-25 16:28:55,009 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:55,094 DEBUG [WebServer] Incoming connection from /192.168.1.14:40090
2012-08-25 16:28:55,095 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,095 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.14:
2012-08-25 16:28:55,095 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,098 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:55,109 DEBUG [WebServer] Incoming connection from /192.168.1.14:40091
2012-08-25 16:28:55,110 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,111 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.14:
2012-08-25 16:28:55,111 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,113 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:55,116 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:55,432 DEBUG [WebServer] Incoming connection from /192.168.1.14:40092
2012-08-25 16:28:55,434 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,435 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device d6dd74bd-7013-3e09-9a39-c83cb088d508 from 192.168.1.14 (headers = [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:55 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";])
2012-08-25 16:28:55,435 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,435 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.99:8895,Date: Sat, 25 Aug 2012 20:28:55 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46EX700";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46EX700"; mv="1.7";]
2012-08-25 16:28:55,436 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,437 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:55,479 DEBUG [WebServer] Incoming connection from /192.168.1.14:40093
2012-08-25 16:28:55,483 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,483 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2012-08-25 16:28:55,484 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:55,529 DEBUG [WebServer] Incoming connection from /192.168.1.14:40094
2012-08-25 16:28:55,530 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,530 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2012-08-25 16:28:55,531 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2012-08-25 16:28:55,627 DEBUG [WebServer] Incoming connection from /192.168.1.14:40095
2012-08-25 16:28:55,628 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,628 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.1.14:
2012-08-25 16:28:55,629 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,632 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:55,640 DEBUG [WebServer] Incoming connection from /192.168.1.14:40096
2012-08-25 16:28:55,641 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,642 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.1.14:
2012-08-25 16:28:55,642 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.14
2012-08-25 16:28:55,644 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV (2010)'
2012-08-25 16:28:55,649 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2012-08-25 16:28:56,106 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:45


Here the output of the command ffmpeg...

  Code:

$ ffmpeg -i /home/media/video/movie.avi -y -threads 2 -copyts -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -r 30000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts /tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf
ffmpeg version git-2012-08-25-65b552c Copyright (c) 2000-2012 the FFmpeg developers
  built on Aug 25 2012 13:33:44 with gcc 4.6 (Ubuntu/Linaro 4.6.3-1ubuntu5)
  configuration: --enable-gpl --enable-libfaac --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libtheora --enable-libvorbis --enable-libx264 --enable-nonfree --enable-postproc --enable-version3 --enable-x11grab --enable-libvpx --enable-librtmp --enable-libxvid
  libavutil      51. 70.100 / 51. 70.100
  libavcodec     54. 54.100 / 54. 54.100
  libavformat    54. 25.104 / 54. 25.104
  libavdevice    54.  2.100 / 54.  2.100
  libavfilter     3. 13.101 /  3. 13.101
  libswscale      2.  1.101 /  2.  1.101
  libswresample   0. 15.100 /  0. 15.100
  libpostproc    52.  0.100 / 52.  0.100
Input #0, avi, from '/home/media/video/movie.avi':
  Metadata:
    encoder         : MEncoder dev-CVS-051014-22:10-3.4.4
  Duration: 01:20:37.03, start: 0.000000, bitrate: 1050 kb/s
    Stream #0:0: Video: mpeg4 (Simple Profile) (XVID / 0x44495658), yuv420p, 512x288 [SAR 1:1 DAR 16:9], 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 44100 Hz, stereo, s16, 160 kb/s
[mpeg2video @ 0x38a3600] impossible bitrate constraints, this will fail
[mpegts @ 0x38a2e60] muxrate VBR, pcr every 2 pkts, sdt every 200, pat/pmt every 40 pkts
Output #0, mpegts, to '/tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf':
  Metadata:
    encoder         : Lavf54.25.104
    Stream #0:0: Video: mpeg2video, yuv420p, 512x288 [SAR 1:1 DAR 16:9], q=2-31, 17000 kb/s, 90k tbn, 29.97 tbc
    Stream #0:1: Audio: ac3, 44100 Hz, stereo, flt, 384 kb/s
Stream mapping:
  Stream #0:0 -> #0:0 (mpeg4 -> mpeg2video)
  Stream #0:1 -> #0:1 (mp3 -> ac3)
Press [q] to stop, [?] for help
frame= 3301 fps=412 q=2.0 size=   33664kB time=00:01:50.07 bitrate=2505.3kbits/s dup=663 drop=0


The pictures and the mp3s are working from the TV. The pictures, mp3s and movies are working from the blu-ray...

I don't see what's the problem with the tv...

I tried to put other types of video in my library, still the same problem.

Do you have an idea what I could try? Thanks.

[Illico]

I tried to put other types of video in my library, still the same problem.

Do you have an idea what I could try? Thanks.

Delete "/tmp/" folder, restart serviio service then retry (you can also check that there were enougth space on tmp directory).

I deleted the /tmp folder, re-created it and restarted. Now ffmpeg is complaining that it doesn't have permission on the file:

  Code:

 ffmpeg -i /home/media/video/movie.avi -y -threads 2 -copyts -c:v mpeg2video -b:v 17000k -maxrate:v 17000k -bufsize:v 17000k -r 30000/1001 -g 15 -c:a ac3 -b:a 384k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts /tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf
ffmpeg version git-2012-08-25-65b552c Copyright (c) 2000-2012 the FFmpeg developers
  built on Aug 25 2012 13:33:44 with gcc 4.6 (Ubuntu/Linaro 4.6.3-1ubuntu5)
  configuration: --enable-gpl --enable-libfaac --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libtheora --enable-libvorbis --enable-libx264 --enable-nonfree --enable-postproc --enable-version3 --enable-x11grab --enable-libvpx --enable-librtmp --enable-libxvid
  libavutil      51. 70.100 / 51. 70.100
  libavcodec     54. 54.100 / 54. 54.100
  libavformat    54. 25.104 / 54. 25.104
  libavdevice    54.  2.100 / 54.  2.100
  libavfilter     3. 13.101 /  3. 13.101
  libswscale      2.  1.101 /  2.  1.101
  libswresample   0. 15.100 /  0. 15.100
  libpostproc    52.  0.100 / 52.  0.100
Input #0, avi, from '/home/media/video/movie.avi':
  Metadata:
    encoder         : MEncoder dev-CVS-051014-22:10-3.4.4
  Duration: 01:20:37.03, start: 0.000000, bitrate: 1050 kb/s
    Stream #0:0: Video: mpeg4 (Simple Profile) (XVID / 0x44495658), yuv420p, 512x288 [SAR 1:1 DAR 16:9], 29.97 tbr, 29.97 tbn, 29.97 tbc
    Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 44100 Hz, stereo, s16, 160 kb/s
/tmp/Serviio/transcoding-temp-6-sony2010-ORIGINAL.stf: Permission denied


The "Serviio" folder in /tmp is own by the user serviio:

  Code:

drwxr-xr-x 2 777 serviio 4096 Aug 27 16:47 Serviio


And I even changed it's permission to 777

What can I do?

[Illico]

delete tmp folder, but do not recreate, restart serviio service, serviio should recreate te folder...
or
create another /tmp_serviio/ folder (change the path on serviio console) with admin rigth access.

I tried not recreating the tmp folder but serviio wasn't able to find it (it did not re-created it).
I recreated it and also a "Serviio" folder inside it, then I chown serviio:serviio /tmp/Serviio and then chmod -R 777 /tmp

Rebooted the server. That did it! It's now working with the TV.

Thank you for your help, very appreciated.

[Illico]

Rebooted the server. That did it! It's now working with the TV.
Thank you for your help, very appreciated.

OK, enjoy it. topic solved.