Problems playing an MKV movie:

Ok, so I've got a Sony kdl-46nx713 and want to stream movies from my Windows 7 PC.

I've installed Serviio 0.6.0.1 and I can play sound files and avi format movies with no issue.

I have a few films in MKV format.

I can see the movie but when I try to play it (it being the MKV movie) I get this message come up on the TV "temporarily cannot access the server. Please retry". When I then try an AVI file movie it works fine.

Any ideas?

Thanks,

Jon

I got this from the error log:

"2011-12-04 17:09:27,704 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.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:117)
at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:93)
at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:121)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:116)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:54)
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:187)"

Thanks,

[Illico]

Could you enable detailed logging ? (see signature links)

Here we go, part 1 of 2:

2011-12-07 19:57:56,047 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44331
2011-12-07 19:57:56,656 INFO [MediaServer] ------------------------------------------------------------------------
2011-12-07 19:57:56,656 INFO [MediaServer] Serviio DLNA media streaming server v 0.6.0.1 (rev. 8e4ee98bff12)
2011-12-07 19:57:56,656 INFO [MediaServer] Petr Nejedly 2009-2011
2011-12-07 19:57:56,656 INFO [MediaServer] http://www.serviio.org
2011-12-07 19:57:56,656 INFO [MediaServer]
2011-12-07 19:57:56,656 INFO [MediaServer] Java 1.6.0_26-Sun Microsystems Inc.
2011-12-07 19:57:56,656 INFO [MediaServer] OS Windows 7 x86 6.1
2011-12-07 19:57:56,656 INFO [MediaServer] File encoding: windows-1252
2011-12-07 19:57:56,796 INFO [MediaServer] Headless mode enabled: true
2011-12-07 19:57:56,796 INFO [MediaServer] User home dir: C:\
2011-12-07 19:57:56,796 INFO [MediaServer] Temp dir: C:\Windows\TEMP\
2011-12-07 19:57:56,796 INFO [MediaServer] ------------------------------------------------------------------------
2011-12-07 19:57:57,124 INFO [RestletServer] Starting Restlet server exposed on port 23423
2011-12-07 19:57:57,186 INFO [DBSchemaUpdateExecutor] Checking if DB schema needs to be updated
2011-12-07 19:57:57,202 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.sql' has been run
2011-12-07 19:57:59,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.1.1.sql' has been run
2011-12-07 19:57:59,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.2.sql' has been run
2011-12-07 19:57:59,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.3.1.sql' has been run
2011-12-07 19:57:59,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.sql' has been run
2011-12-07 19:57:59,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.1.sql' has been run
2011-12-07 19:57:59,651 DEBUG [DBLogDAOImpl] Checking if script 'script-0.4.2.sql' has been run
2011-12-07 19:57:59,666 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.sql' has been run
2011-12-07 19:57:59,666 DEBUG [DBLogDAOImpl] Checking if script 'script-0.5.1.sql' has been run
2011-12-07 19:57:59,666 DEBUG [DBLogDAOImpl] Checking if script 'script-0.6.sql' has been run
2011-12-07 19:57:59,947 INFO [ProfilesDefinitionParser] Parsing Profiles definition
2011-12-07 19:58:00,010 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 1
2011-12-07 19:58:00,602 INFO [ProfilesDefinitionParser] Added profile 'Generic DLNA profile' (id=1)
2011-12-07 19:58:00,602 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 2
2011-12-07 19:58:00,977 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (B-series)' (id=2)
2011-12-07 19:58:00,977 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 3
2011-12-07 19:58:01,164 INFO [ProfilesDefinitionParser] Added profile 'XBox 360' (id=3)
2011-12-07 19:58:01,164 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 4
2011-12-07 19:58:01,351 INFO [ProfilesDefinitionParser] Added profile 'Playstation 3' (id=4)
2011-12-07 19:58:01,351 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 5
2011-12-07 19:58:01,507 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV (A-series)' (id=5)
2011-12-07 19:58:01,507 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 6
2011-12-07 19:58:01,648 INFO [ProfilesDefinitionParser] Added profile 'DirecTV HD-DVR' (id=6)
2011-12-07 19:58:01,648 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 7
2011-12-07 19:58:02,006 INFO [ProfilesDefinitionParser] Added profile 'Samsung TV / player (C/D-series)' (id=7)
2011-12-07 19:58:02,006 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 8
2011-12-07 19:58:02,069 INFO [ProfilesDefinitionParser] Added profile 'LG TV / player' (id=8)
2011-12-07 19:58:02,069 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 9
2011-12-07 19:58:02,568 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV' (id=9)
2011-12-07 19:58:02,568 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 10
2011-12-07 19:58:03,286 INFO [ProfilesDefinitionParser] Added profile 'Sony BD Player' (id=10)
2011-12-07 19:58:03,286 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 12
2011-12-07 19:58:03,582 INFO [ProfilesDefinitionParser] Added profile 'Panasonic Viera' (id=12)
2011-12-07 19:58:03,582 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 13
2011-12-07 19:58:03,972 INFO [ProfilesDefinitionParser] Added profile 'Toshiba REGZA' (id=13)
2011-12-07 19:58:03,988 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 14
2011-12-07 19:58:04,128 INFO [ProfilesDefinitionParser] Added profile 'WDTV Live' (id=14)
2011-12-07 19:58:04,128 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 15
2011-12-07 19:58:04,175 INFO [ProfilesDefinitionParser] Added profile 'LaCie LaCinema Media Player' (id=15)
2011-12-07 19:58:04,175 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 16
2011-12-07 19:58:04,315 INFO [ProfilesDefinitionParser] Added profile 'Manta WIFI Centre' (id=16)
2011-12-07 19:58:04,315 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 17
2011-12-07 19:58:04,393 INFO [ProfilesDefinitionParser] Added profile 'Pure Flow' (id=17)
2011-12-07 19:58:04,409 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 18
2011-12-07 19:58:04,580 INFO [ProfilesDefinitionParser] Added profile 'Sharp Aquos' (id=18)
2011-12-07 19:58:04,580 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 19
2011-12-07 19:58:05,142 INFO [ProfilesDefinitionParser] Added profile 'Sony Bravia TV 2011' (id=19)
2011-12-07 19:58:05,142 DEBUG [ProfilesDefinitionParser] Parsing profile definition for profile 20
2011-12-07 19:58:05,641 INFO [ProfilesDefinitionParser] Added profile 'Sony SMP-N100' (id=20)
2011-12-07 19:58:05,672 INFO [CompositeCacheManager] Creating cache manager from config file: /cache.ccf
2011-12-07 19:58:05,688 INFO [ThreadPoolManager] thread_pool.default PoolConfiguration = useBoundary = [true] boundarySize = [2000] maximumPoolSize = [150] minimumPoolSize = [4] keepAliveTime = [300000] whenBlockedPolicy = [RUN] startUpSize = [4]
2011-12-07 19:58:05,704 INFO [CompositeCacheConfigurator] Setting default auxiliaries to null
2011-12-07 19:58:05,704 INFO [CompositeCacheConfigurator] No special CompositeCacheAttributes class defined for key [jcs.default.cacheattributes], using default class.
2011-12-07 19:58:05,704 INFO [CompositeCacheConfigurator] setting defaultCompositeCacheAttributes to [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 100, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-12-07 19:58:05,704 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.default.elementattributes], using default class.
2011-12-07 19:58:05,704 INFO [CompositeCacheConfigurator] setting defaultElementAttributes to [ IS_LATERAL = true, IS_SPOOL = true, IS_REMOTE = true, IS_ETERNAL = true, MaxLifeSeconds = -1, IdleTime = -1, CreateTime = 1323287885704, LastAccessTime = 1323287885704, getTimeToLiveSeconds() = -1, createTime = 1323287885704 ]
2011-12-07 19:58:05,750 INFO [LRUMemoryCache] initialized LRUMemoryCache for thumbnails
2011-12-07 19:58:05,750 INFO [CompositeCache] Constructed cache with name [thumbnails] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-12-07 19:58:05,750 INFO [LRUMemoryCache] initialized LRUMemoryCache for online_feeds
2011-12-07 19:58:05,750 INFO [CompositeCache] Constructed cache with name [online_feeds] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-12-07 19:58:05,766 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_default.elementattributes], using default class.
2011-12-07 19:58:05,766 INFO [LRUMemoryCache] initialized LRUMemoryCache for local_default
2011-12-07 19:58:05,766 INFO [CompositeCache] Constructed cache with name [local_default] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-12-07 19:58:05,766 INFO [CompositeCacheConfigurator] No special ElementAttribute class defined for key [jcs.region.local_resetafterplay.elementattributes], using default class.
2011-12-07 19:58:05,766 INFO [LRUMemoryCache] initialized LRUMemoryCache for local_resetafterplay
2011-12-07 19:58:05,766 INFO [CompositeCache] Constructed cache with name [local_resetafterplay] and cache attributes [ useLateral = true, useRemote = true, useDisk = true, maxObjs = 1000, maxSpoolPerRun = -1, diskUsagePattern = 0 ]
2011-12-07 19:58:05,766 INFO [CompositeCacheConfigurator] Parsed regions [thumbnails, online_feeds, local_default, local_resetafterplay]
2011-12-07 19:58:05,766 INFO [CompositeCacheConfigurator] Finished configuration in 62 ms.
2011-12-07 19:58:05,813 DEBUG [ConfigEntryDAOImpl] Reading all ConfigEntries
2011-12-07 19:58:06,686 INFO [WebServer] Socket buffer set to 65535 bytes
2011-12-07 19:58:06,686 INFO [Device] Created UPnP Device with UUID: c5db91ac-54f5-3daa-b7ba-6fea634c26b1, bound address: 192.168.0.3
2011-12-07 19:58:06,718 INFO [PluginCompilerThread] Looking for plugins at C:\Program Files\Serviio\bin\..\plugins
2011-12-07 19:58:06,733 INFO [PluginCompilerThread] Started looking for plugins
2011-12-07 19:58:06,764 DEBUG [FFMPEGWrapper] Invoking FFMPEG to check if it exists of path C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2011-12-07 19:58:06,764 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe
2011-12-07 19:58:06,920 INFO [AbstractTranscodingDeliveryEngine] Cleaning transcode engine and its data
2011-12-07 19:58:06,920 DEBUG [AbstractTranscodingDeliveryEngine] Deleting temporary transcoded files from: C:\Windows\TEMP\Serviio
2011-12-07 19:58:06,998 INFO [WebServer] WebServer starting on port 8895
2011-12-07 19:58:07,014 DEBUG [DiscoveryManager] UPNP device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 (192.168.0.3) is available
2011-12-07 19:58:07,014 INFO [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2011-12-07 19:58:07,014 INFO [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2011-12-07 19:58:07,014 INFO [RendererExpirationChecker] Starting RendererExpirationChecker
2011-12-07 19:58:07,045 INFO [RendererSearchSender] Searching for Renderer devices
2011-12-07 19:58:07,046 INFO [EventDispatcher] Starting EventDispatcher
2011-12-07 19:58:07,156 INFO [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface net3 (Intel(R) PRO/Wireless 3945ABG Network Connection) and address 192.168.0.3, timeout = 0
2011-12-07 19:58:07,578 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:07,702 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net3 (Intel(R) PRO/Wireless 3945ABG Network Connection) and address 192.168.0.3, timeout = 0
2011-12-07 19:58:07,702 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c5db91ac-54f5-3daa-b7ba-6fea634c26b1
2011-12-07 19:58:07,921 DEBUG [RendererSearchSender] Multicasting SSDP M-SEARCH using interface net3 (Intel(R) PRO/Wireless 3945ABG Network Connection) and address 192.168.0.3, timeout = 0
2011-12-07 19:58:07,921 DEBUG [RendererSearchSender] Sending 1 'm-search' messages
2011-12-07 19:58:07,921 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.3:59004
2011-12-07 19:58:07,921 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.3:59004
2011-12-07 19:58:07,921 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.3:59004
2011-12-07 19:58:08,404 DEBUG [WebServer] Incoming connection from /192.168.0.2:45022
2011-12-07 19:58:08,436 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.2 (headers = [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:02 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";])
2011-12-07 19:58:08,436 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:08,451 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:08,451 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:08,451 DEBUG [RendererSearchSender] Received search response: location: http://192.168.0.2:52323/dmr.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-12-07 19:58:08,451 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer 00000000-0000-1010-8000-00a09643a52e from address /192.168.0.2:33521
2011-12-07 19:58:08,467 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:02 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";]
2011-12-07 19:58:08,467 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:08,482 DEBUG [RendererManager] Adding renderer Renderer [uuid=00000000-0000-1010-8000-00a09643a52e, ipAddress=192.168.0.2, name=KDL-46NX713, profileId=9, manuallyAdded=false] to list of active renderers
2011-12-07 19:58:08,826 DEBUG [RendererSearchSender] Received search response: location: http://192.168.0.2:52323/dmr.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-12-07 19:58:08,826 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer 00000000-0000-1010-8000-00a09643a52e from address /192.168.0.2:33521
2011-12-07 19:58:09,340 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV'
2011-12-07 19:58:09,356 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:09,387 DEBUG [WebServer] Incoming connection from /192.168.0.2:45023
2011-12-07 19:58:09,387 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-12-07 19:58:09,403 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:09,434 DEBUG [WebServer] Incoming connection from /192.168.0.2:45024
2011-12-07 19:58:09,434 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-12-07 19:58:09,528 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:09,652 DEBUG [WebServer] Incoming connection from /192.168.0.2:45025
2011-12-07 19:58:09,668 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-12-07 19:58:09,684 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.2:
2011-12-07 19:58:09,684 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:10,557 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:11,056 DEBUG [RendererSearchSender] Received search response: location: http://192.168.0.2:52323/dmr.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2011-12-07 19:58:11,056 DEBUG [RendererSearchSender] Received a valid M-SEARCH response from Renderer 00000000-0000-1010-8000-00a09643a52e from address /192.168.0.2:33521
2011-12-07 19:58:11,462 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:11,462 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:12,086 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:12,102 DEBUG [WebServer] Incoming connection from /192.168.0.2:45026
2011-12-07 19:58:12,117 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.2:
2011-12-07 19:58:12,117 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:12,320 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV'
2011-12-07 19:58:12,351 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:12,585 DEBUG [WebServer] Incoming connection from /192.168.0.2:45027
2011-12-07 19:58:12,585 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.2 (headers = [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:06 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";])
2011-12-07 19:58:12,585 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:12,585 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:06 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";]
2011-12-07 19:58:12,601 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:12,601 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV'
2011-12-07 19:58:12,694 DEBUG [WebServer] Incoming connection from /192.168.0.2:45028
2011-12-07 19:58:12,694 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-12-07 19:58:12,694 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:12,741 DEBUG [WebServer] Incoming connection from /192.168.0.2:45029
2011-12-07 19:58:12,741 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon largeJPG
2011-12-07 19:58:12,757 DEBUG [WebServer] Incoming connection from /192.168.0.2:45030
2011-12-07 19:58:12,757 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-12-07 19:58:12,757 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:12,835 DEBUG [WebServer] Incoming connection from /192.168.0.2:45031
2011-12-07 19:58:12,850 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.2:
2011-12-07 19:58:12,850 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:13,038 DEBUG [WebServer] Incoming connection from /192.168.0.2:45032
2011-12-07 19:58:13,038 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon largeJPG
2011-12-07 19:58:13,147 DEBUG [WebServer] Incoming connection from /192.168.0.2:45033
2011-12-07 19:58:13,147 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon largeJPG
2011-12-07 19:58:13,225 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:13,256 DEBUG [WebServer] Incoming connection from /192.168.0.2:45034
2011-12-07 19:58:13,256 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.2:
2011-12-07 19:58:13,256 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:13,490 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV'
2011-12-07 19:58:13,568 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:13,599 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:13,927 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2011-12-07 19:58:16,605 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:19,616 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:19,850 DEBUG [ServiioConsole] Initializing Serviio Console
2011-12-07 19:58:19,912 DEBUG [ApplicationInstanceManager] Listening for application instances on socket 44332
2011-12-07 19:58:19,912 INFO [ServiioConsole] Starting Serviio Console
2011-12-07 19:58:20,177 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net3 (Intel(R) PRO/Wireless 3945ABG Network Connection) and address 192.168.0.3, timeout = 0
2011-12-07 19:58:20,177 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c5db91ac-54f5-3daa-b7ba-6fea634c26b1
2011-12-07 19:58:20,832 DEBUG [WebServer] Incoming connection from /192.168.0.2:45035
2011-12-07 19:58:20,848 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.2 (headers = [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:14 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";])
2011-12-07 19:58:20,848 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:20,848 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:14 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";]
2011-12-07 19:58:20,848 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:20,848 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV'
2011-12-07 19:58:20,879 DEBUG [WebServer] Incoming connection from /192.168.0.2:45036
2011-12-07 19:58:20,879 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-12-07 19:58:20,879 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:20,910 DEBUG [WebServer] Incoming connection from /192.168.0.2:45037
2011-12-07 19:58:20,926 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-12-07 19:58:20,926 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:21,051 DEBUG [WebServer] Incoming connection from /192.168.0.2:45038
2011-12-07 19:58:21,051 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.2:
2011-12-07 19:58:21,051 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:21,269 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:21,300 DEBUG [WebServer] Incoming connection from /192.168.0.2:45039
2011-12-07 19:58:21,300 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.2:
2011-12-07 19:58:21,300 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:21,503 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV'
2011-12-07 19:58:21,519 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:21,722 DEBUG [WebServer] Incoming connection from /192.168.0.2:45040
2011-12-07 19:58:21,722 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.2 (headers = [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:15 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";])
2011-12-07 19:58:21,722 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:21,722 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:15 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";]
2011-12-07 19:58:21,722 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:21,722 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV'
2011-12-07 19:58:21,753 DEBUG [WebServer] Incoming connection from /192.168.0.2:45041
2011-12-07 19:58:21,753 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-12-07 19:58:21,753 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:21,784 DEBUG [WebServer] Incoming connection from /192.168.0.2:45042
2011-12-07 19:58:21,800 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-12-07 19:58:21,800 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:21,831 DEBUG [SwingHelper] Choosing java look and feel: com.sun.java.swing.plaf.windows.WindowsLookAndFeel
2011-12-07 19:58:21,909 DEBUG [WebServer] Incoming connection from /192.168.0.2:45043
2011-12-07 19:58:21,909 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.2:
2011-12-07 19:58:21,909 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:22,127 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:22,143 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:00:10
2011-12-07 19:58:22,143 DEBUG [WebServer] Incoming connection from /192.168.0.2:45044
2011-12-07 19:58:22,143 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.2:
2011-12-07 19:58:22,143 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:22,346 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV'
2011-12-07 19:58:22,361 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:22,626 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:23,204 INFO [ServiioConsole] Connecting to Serviio REST on localhost:23423
2011-12-07 19:58:29,179 INFO [Messages] Loaded console message bundle for locale: en
2011-12-07 19:58:31,144 DEBUG [ServiioConsole] Retrieving preferred console language
2011-12-07 19:58:31,706 DEBUG [ServiioConsole] Setting console locale to be: en
2011-12-07 19:58:31,706 INFO [Messages] Loaded console message bundle for locale: en
2011-12-07 19:58:31,831 INFO [UpdateChecker] Checking if a new version is available
2011-12-07 19:58:32,626 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface net3 (Intel(R) PRO/Wireless 3945ABG Network Connection) and address 192.168.0.3, timeout = 0
2011-12-07 19:58:32,642 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device c5db91ac-54f5-3daa-b7ba-6fea634c26b1
2011-12-07 19:58:33,141 DEBUG [ServiioFrameView] Initializing main frame
2011-12-07 19:58:33,313 DEBUG [WebServer] Incoming connection from /192.168.0.2:45045
2011-12-07 19:58:33,313 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.2 (headers = [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:27 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";])
2011-12-07 19:58:33,313 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:33,313 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:27 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";]
2011-12-07 19:58:33,313 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:33,313 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV'
2011-12-07 19:58:33,344 DEBUG [WebServer] Incoming connection from /192.168.0.2:45046
2011-12-07 19:58:33,359 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-12-07 19:58:33,359 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:33,391 DEBUG [WebServer] Incoming connection from /192.168.0.2:45047
2011-12-07 19:58:33,391 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-12-07 19:58:33,391 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:33,484 DEBUG [WebServer] Incoming connection from /192.168.0.2:45048
2011-12-07 19:58:33,484 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.2:
2011-12-07 19:58:33,484 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:33,703 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:33,718 DEBUG [WebServer] Incoming connection from /192.168.0.2:45049
2011-12-07 19:58:33,718 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.2:
2011-12-07 19:58:33,718 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:33,874 DEBUG [ServiioFrameView] System tray is supported
2011-12-07 19:58:34,451 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV'
2011-12-07 19:58:34,467 INFO [FeedUpdaterThread] Started looking for information about online feeds
2011-12-07 19:58:34,623 INFO [LibraryAdditionsCheckerThread] Started looking for newly added files
2011-12-07 19:58:34,826 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:34,826 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-12-07 19:58:34,826 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-12-07 19:58:34,997 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:01:05
2011-12-07 19:58:35,125 DEBUG [WebServer] Incoming connection from /192.168.0.2:45050
2011-12-07 19:58:35,126 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.2 (headers = [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:28 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";])
2011-12-07 19:58:35,126 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:35,163 INFO [LibraryUpdatesCheckerThread] Started looking for updates to currently shared files
2011-12-07 19:58:35,163 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-12-07 19:58:35,339 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.0.3:8895,Date: Wed, 07 Dec 2011 19:58:28 GMT,User-Agent: UPnP/1.0,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";]
2011-12-07 19:58:35,340 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:35,640 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Sony Bravia TV'
2011-12-07 19:58:35,673 DEBUG [WebServer] Incoming connection from /192.168.0.2:45051
2011-12-07 19:58:35,673 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2011-12-07 19:58:35,674 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:35,720 DEBUG [WebServer] Incoming connection from /192.168.0.2:45052
2011-12-07 19:58:35,720 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2011-12-07 19:58:35,721 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2011-12-07 19:58:35,842 DEBUG [WebServer] Incoming connection from /192.168.0.2:45053
2011-12-07 19:58:35,844 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#GetSortCapabilities"' from 192.168.0.2:
2011-12-07 19:58:35,844 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:35,993 DEBUG [LibraryUpdatesCheckerThread] Looking for removed/updated files in repository: C:\Users\IBM X60s\Videos\Movies
2011-12-07 19:58:36,081 DEBUG [MediaItemDAOImpl] Reading MediaItems for Repository (id = 1)
2011-12-07 19:58:36,085 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:36,086 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: C:\Users\IBM X60s\Videos\Movies
2011-12-07 19:58:36,104 DEBUG [WebServer] Incoming connection from /192.168.0.2:45054
2011-12-07 19:58:36,105 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ConnectionManager:1#GetProtocolInfo"' from 192.168.0.2:
2011-12-07 19:58:36,105 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:58:36,194 DEBUG [LibraryAdditionsCheckerThread] Found file 'Bad.Teacher.2011.UNRATED.DVDRip.XviD-ViP3R.avi', checking if it's already in the Library
2011-12-07 19:58:36,194 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item Bad.Teacher.2011.UNRATED.DVDRip.XviD-ViP3R.avi
2011-12-07 19:58:36,376 DEBUG [ConnectionManager] Sending protocol info using profile 'Sony Bravia TV'
2011-12-07 19:58:36,385 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:58:39,159 DEBUG [MediaItemDAOImpl] Getting file of media item 1
2011-12-07 19:58:39,174 DEBUG [MediaItemDAOImpl] Media item Bad.Teacher.2011.UNRATED.DVDRip.XviD-ViP3R.avi already exists in DB
2011-12-07 19:58:39,241 DEBUG [LibraryAdditionsCheckerThread] Found file 'The Godfather Part I (1972).avi', checking if it's already in the Library
2011-12-07 19:58:39,241 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item The Godfather Part I (1972).avi
2011-12-07 19:58:39,243 DEBUG [MediaItemDAOImpl] Media item The Godfather Part I (1972).avi already exists in DB
2011-12-07 19:58:39,264 DEBUG [LibraryAdditionsCheckerThread] Found file 'The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv', checking if it's already in the Library
2011-12-07 19:58:39,264 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv
2011-12-07 19:58:39,266 DEBUG [MediaItemDAOImpl] Media item The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv already exists in DB
2011-12-07 19:58:39,303 DEBUG [RepositoryDAOImpl] Marking Repository 1 as scanned with current timestamp
2011-12-07 19:58:39,646 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2011-12-07 19:58:40,414 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-12-07 19:58:40,459 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-12-07 19:58:40,496 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2011-12-07 19:58:40,497 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type IMAGE
2011-12-07 19:58:40,501 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 1) and extractor EMBEDDED
2011-12-07 19:58:41,113 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 1) and extractor COVER_IMAGE_IN_FOLDER
2011-12-07 19:58:41,164 DEBUG [MediaItemDAOImpl] Getting file of media item 379
2011-12-07 19:58:41,167 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 379) and extractor EMBEDDED
2011-12-07 19:58:41,171 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 379) and extractor COVER_IMAGE_IN_FOLDER
2011-12-07 19:58:41,176 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2011-12-07 19:58:41,222 DEBUG [MediaItemDAOImpl] Getting file of media item 380
2011-12-07 19:58:41,224 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 380) and extractor EMBEDDED
2011-12-07 19:58:41,225 DEBUG [MetadataDescriptorDAOImpl] Reading MetadataDescriptor for MediaItem (id = 380) and extractor COVER_IMAGE_IN_FOLDER
2011-12-07 19:58:41,225 DEBUG [MediaItemDAOImpl] Reading dirty MediaItems for Repository (id = 1)
2011-12-07 19:58:41,264 DEBUG [RepositoryDAOImpl] Marking Repository 1 as scanned with current timestamp
2011-12-07 19:58:43,275 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type AUDIO
2011-12-07 19:58:43,277 DEBUG [MetadataExtractorConfigDAOImpl] Reading a list of Extractor configuration for type VIDEO
2011-12-07 19:58:49,107 INFO [ContentDirectoryDefinitionParser] Parsing ContentDirectory definition
2011-12-07 19:58:49,112 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=0, titleKey=pc5dczc_media_library, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = null
2011-12-07 19:58:49,306 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A, titleKey=audio, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,314 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_A, titleKey=artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,320 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,329 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,335 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForArtistCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,343 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForArtistAndAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,349 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=AL_A, titleKey=album_artists, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,356 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListAlbumArtistInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,362 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListAlbumArtistsForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,370 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForAlbumArtistCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,376 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,383 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,389 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=MUSIC_TRACK, idPrefix=FOL, recursive=true, cacheRegion=local_default
2011-12-07 19:58:49,396 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_G, titleKey=genres, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,402 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioGenresByNameCommand, containerClass=MUSIC_GENRE, itemClass=null, idPrefix=GEN, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,408 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForGenreCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,415 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_C, titleKey=composers, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,421 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListComposerInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,429 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListComposersForInitialCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=COM, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,435 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAlbumsForComposerCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=A, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,443 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForComposerAndAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,449 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_T, titleKey=titles, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,457 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,463 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForInitialCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,471 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_R, titleKey=random, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2011-12-07 19:58:49,477 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListRandomSongsCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:49,484 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_OF, titleKey=online_feeds, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2011-12-07 19:58:49,491 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAudioOnlineFeedsByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=REPO, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:49,497 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForOnlineFeedCommand, containerClass=null, itemClass=AUDIO_ITEM, idPrefix=F, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:49,505 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_S, titleKey=All songs, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2011-12-07 19:58:49,511 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAllSongsCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,519 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_AS, titleKey=Artist Songs, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2011-12-07 19:58:49,525 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListArtistsByNameCommand, containerClass=MUSIC_ARTIST, itemClass=null, idPrefix=ART_S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,533 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForArtistCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,540 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=A_ALB, titleKey=All albums, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null
2011-12-07 19:58:49,547 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListAllAlbumsCommand, containerClass=MUSIC_ALBUM, itemClass=null, idPrefix=ALB, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,554 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.audio.ListSongsForAlbumCommand, containerClass=null, itemClass=MUSIC_TRACK, idPrefix=S, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,562 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I, titleKey=image, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,568 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_A, titleKey=albums, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,576 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListFlatImageFoldersByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=ALB, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,583 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForFolderCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,590 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,598 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImageFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=PHOTO, idPrefix=FOL, recursive=true, cacheRegion=local_default
2011-12-07 19:58:49,604 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_Y, titleKey=year, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,613 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationYearsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=YEAR, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,620 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForCreationYearCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,629 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_T, titleKey=month, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,635 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationYearsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=YEAR, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,644 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesCreationMonthsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=MON, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,651 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForCreationMonthAndYearCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,660 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_OF, titleKey=online_feeds, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2011-12-07 19:58:49,666 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImageOnlineFeedsByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=REPO, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:49,675 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListImagesForOnlineFeedCommand, containerClass=null, itemClass=PHOTO, idPrefix=F, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:49,682 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=I_AI, titleKey=All images, class=CONTAINER, cacheRegion=local_default, browsable = false, editable = null

part 2 of 2:

2011-12-07 19:58:49,690 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.image.ListAllImagesCommand, containerClass=null, itemClass=PHOTO, idPrefix=I, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,697 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V, titleKey=video, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,704 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_T, titleKey=titles, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,713 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,720 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForInitialCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,730 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_M, titleKey=movies, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,737 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListMovieVideosByNameCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,745 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_S, titleKey=series, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,753 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListSeriesByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=SER, recursive=false, cacheRegion=local_resetafterplay
2011-12-07 19:58:49,762 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListSeasonsForSeriesCommand, containerClass=CONTAINER, itemClass=null, idPrefix=SSN, recursive=false, cacheRegion=local_resetafterplay
2011-12-07 19:58:49,769 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListEpisodesForSeriesSeasonCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_resetafterplay
2011-12-07 19:58:49,778 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_F, titleKey=folders, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,785 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoFoldersByNameCommand, containerClass=STORAGE_FOLDER, itemClass=VIDEO_ITEM, idPrefix=FOL, recursive=true, cacheRegion=local_default
2011-12-07 19:58:49,793 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LV, titleKey=last_viewed, class=CONTAINER, cacheRegion=local_resetafterplay, browsable = null, editable = true
2011-12-07 19:58:49,800 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListLastViewedVideosCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_resetafterplay
2011-12-07 19:58:49,809 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_LA, titleKey=last_added, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,817 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListLastAddedVideosCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,825 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_G, titleKey=genres, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,833 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoGenresByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=GEN, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,842 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForGenreCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,849 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_A, titleKey=actors, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,885 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListActorInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,893 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListActorsForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,901 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForActorCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,911 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_D, titleKey=directors, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,919 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListDirectorInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,928 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListDirectorsForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,936 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForDirectorCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,945 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_P, titleKey=producers, class=CONTAINER, cacheRegion=local_default, browsable = null, editable = true
2011-12-07 19:58:49,952 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListProducerInitialsCommand, containerClass=CONTAINER, itemClass=null, idPrefix=INI, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,961 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.person.ListProducersForInitialCommand, containerClass=PERSON, itemClass=null, idPrefix=P, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,969 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForProducerCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=V, recursive=false, cacheRegion=local_default
2011-12-07 19:58:49,978 DEBUG [ContentDirectoryDefinitionParser] Found Container node with attributes: id=V_OF, titleKey=online_feeds, class=CONTAINER, cacheRegion=no_cache, browsable = null, editable = true
2011-12-07 19:58:49,986 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideoOnlineFeedsByNameCommand, containerClass=CONTAINER, itemClass=null, idPrefix=REPO, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:49,996 DEBUG [ContentDirectoryDefinitionParser] Found Action node with attributes: command=org.serviio.upnp.service.contentdirectory.command.video.ListVideosForOnlineFeedCommand, containerClass=null, itemClass=VIDEO_ITEM, idPrefix=F, recursive=false, cacheRegion=no_cache
2011-12-07 19:58:50,067 INFO [BrowsingCategoriesMessages] Loaded browsing categories message bundle for locale: en
2011-12-07 19:58:54,667 DEBUG [RepositoryDAOImpl] Reading all Repositories
2011-12-07 19:58:54,668 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-12-07 19:58:54,775 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:54,780 DEBUG [RepositoryView$LibraryStatusCheckerThread] Starting library status checking thread
2011-12-07 19:58:56,922 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:57,776 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:58:59,922 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:59:00,776 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:59:01,498 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.3:56536
2011-12-07 19:59:01,500 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.3:56536
2011-12-07 19:59:01,500 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.3:56536
2011-12-07 19:59:02,488 DEBUG [WebServer] Incoming connection from /192.168.0.3:49239
2011-12-07 19:59:02,489 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 from 192.168.0.3 (headers = [Cache-Control: no-cache,Connection: Close,Pragma: no-cache,Accept: text/xml, application/xml,Host: 192.168.0.3:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50])
2011-12-07 19:59:02,489 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.3
2011-12-07 19:59:02,490 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.3:8895,User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50]
2011-12-07 19:59:02,490 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.3
2011-12-07 19:59:02,492 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'Generic DLNA profile'
2011-12-07 19:59:02,591 DEBUG [WebServer] Incoming connection from /192.168.0.3:49240
2011-12-07 19:59:02,591 DEBUG [UPnPIconRequestHandler] UPnP icon request received for icon smallPNG
2011-12-07 19:59:02,922 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:59:04,498 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.3:56536
2011-12-07 19:59:04,500 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.3:56536
2011-12-07 19:59:04,501 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.3:56536
2011-12-07 19:59:05,942 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:59:05,952 DEBUG [WebServer] Incoming connection from /192.168.0.2:45067
2011-12-07 19:59:05,953 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.2:
2011-12-07 19:59:05,954 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:06,159 DEBUG [ContentDirectory] Browse() called for renderer KDL-46NX713 (profile 9) 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 =
2011-12-07 19:59:06,438 DEBUG [VideoDAOImpl] Retrieving number of video initials
2011-12-07 19:59:06,633 DEBUG [VideoDAOImpl] Retrieving number of videos of type 2
2011-12-07 19:59:06,652 DEBUG [SeriesDAOImpl] Retrieving number of series
2011-12-07 19:59:06,765 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO
2011-12-07 19:59:06,775 DEBUG [VideoDAOImpl] Retrieving number of 10 last viewed videos
2011-12-07 19:59:06,793 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-12-07 19:59:06,793 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-12-07 19:59:06,812 DEBUG [GenreDAOImpl] Retrieving number of genres for VIDEO
2011-12-07 19:59:06,913 DEBUG [PersonDAOImpl] Retrieving number of person initials for role ACTOR
2011-12-07 19:59:07,498 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.0.3:56536
2011-12-07 19:59:07,500 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaServer:1 from address /192.168.0.3:56536
2011-12-07 19:59:07,501 DEBUG [DiscoverySearchResponder] Sending 1 M-SEARCH response message(s) to /192.168.0.3:56536
2011-12-07 19:59:08,398 DEBUG [PersonDAOImpl] Retrieving number of person initials for role DIRECTOR
2011-12-07 19:59:08,401 DEBUG [PersonDAOImpl] Retrieving number of person initials for role PRODUCER
2011-12-07 19:59:08,405 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-12-07 19:59:08,415 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:59:08,611 DEBUG [WebServer] Incoming connection from /192.168.0.2:45076
2011-12-07 19:59:08,613 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.2:
2011-12-07 19:59:08,613 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:08,815 DEBUG [ContentDirectory] Browse() called for renderer KDL-46NX713 (profile 9) 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 =
2011-12-07 19:59:08,852 DEBUG [OnlineRepositoryDAOImpl] Retrieving number of OnlineRepositories for VIDEO
2011-12-07 19:59:08,859 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-12-07 19:59:08,862 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:59:08,942 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:59:11,476 DEBUG [WebServer] Incoming connection from /192.168.0.2:45086
2011-12-07 19:59:11,476 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.2:
2011-12-07 19:59:11,476 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:11,684 DEBUG [ContentDirectory] Browse() called for renderer KDL-46NX713 (profile 9) with parameters: objectID = V_LA, 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 =
2011-12-07 19:59:11,684 DEBUG [VideoDAOImpl] Retrieving list of 10 last added videos (from=0, count=10)
2011-12-07 19:59:11,773 DEBUG [GenreDAOImpl] Reading a Genre (id = 22)
2011-12-07 19:59:11,781 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1)
2011-12-07 19:59:11,791 DEBUG [MediaItemDAOImpl] Getting file of media item 1
2011-12-07 19:59:11,796 DEBUG [VideoDAOImpl] Reading a Video (id = 1)
2011-12-07 19:59:11,811 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1 and profile Sony Bravia TV
2011-12-07 19:59:11,814 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv: AVC_TS_MP_SD_AC3_ISO
2011-12-07 19:59:11,817 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 265)
2011-12-07 19:59:11,943 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:InternetGatewayDevice:1 from address /192.168.0.3:56536
2011-12-07 19:59:12,022 DEBUG [GenreDAOImpl] Reading a Genre (id = 22)
2011-12-07 19:59:12,023 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 380)
2011-12-07 19:59:12,023 DEBUG [MediaItemDAOImpl] Getting file of media item 380
2011-12-07 19:59:12,025 DEBUG [VideoDAOImpl] Reading a Video (id = 380)
2011-12-07 19:59:12,026 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 380 and profile Sony Bravia TV
2011-12-07 19:59:12,026 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bad.Teacher.2011.UNRATED.DVDRip.XviD-ViP3R.avi: MPEG_TS_SD_EU_ISO
2011-12-07 19:59:12,026 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bad.Teacher.2011.UNRATED.DVDRip.XviD-ViP3R.avi: MPEG_TS_SD_NA_ISO
2011-12-07 19:59:12,026 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Bad.Teacher.2011.UNRATED.DVDRip.XviD-ViP3R.avi: MPEG_TS_SD_KO_ISO
2011-12-07 19:59:12,027 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 267)
2011-12-07 19:59:12,028 DEBUG [GenreDAOImpl] Reading a Genre (id = 22)
2011-12-07 19:59:12,028 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 379)
2011-12-07 19:59:12,029 DEBUG [MediaItemDAOImpl] Getting file of media item 379
2011-12-07 19:59:12,030 DEBUG [VideoDAOImpl] Reading a Video (id = 379)
2011-12-07 19:59:12,031 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 379 and profile Sony Bravia TV
2011-12-07 19:59:12,031 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Godfather Part I (1972).avi: MPEG_TS_SD_EU_ISO
2011-12-07 19:59:12,032 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Godfather Part I (1972).avi: MPEG_TS_SD_NA_ISO
2011-12-07 19:59:12,032 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Godfather Part I (1972).avi: MPEG_TS_SD_KO_ISO
2011-12-07 19:59:12,032 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 266)
2011-12-07 19:59:12,033 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2011-12-07 19:59:12,033 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0
2011-12-07 19:59:12,034 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2011-12-07 19:59:12,044 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:59:12,297 DEBUG [WebServer] Incoming connection from /192.168.0.2:45090
2011-12-07 19:59:12,306 DEBUG [ResourceTransportRequestHandler] GET /resource/265/COVER_IMAGE HTTP/1.1, headers = [transferMode.dlna.org: Interactive,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:12,306 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:12,308 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:12,309 DEBUG [ResourceTransportRequestHandler] Request for resource 265 and type 'COVER_IMAGE' received
2011-12-07 19:59:12,310 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 265)
2011-12-07 19:59:12,311 DEBUG [CoverImageRetrievalStrategy] Retrieving Cover image with id 265
2011-12-07 19:59:12,314 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 3360
2011-12-07 19:59:12,314 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-12-07 19:59:12,314 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: image/jpeg,Content-Length: 3360,Date: Wed, 07 Dec 2011 19:59:12 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,transferMode.dlna.org: Interactive]]
2011-12-07 19:59:12,403 DEBUG [WebServer] Incoming connection from /192.168.0.2:45091
2011-12-07 19:59:12,403 DEBUG [ResourceTransportRequestHandler] GET /resource/267/COVER_IMAGE HTTP/1.1, headers = [transferMode.dlna.org: Interactive,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:12,403 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:12,404 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:12,404 DEBUG [ResourceTransportRequestHandler] Request for resource 267 and type 'COVER_IMAGE' received
2011-12-07 19:59:12,404 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 267)
2011-12-07 19:59:12,405 DEBUG [CoverImageRetrievalStrategy] Retrieving Cover image with id 267
2011-12-07 19:59:12,405 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 4469
2011-12-07 19:59:12,405 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-12-07 19:59:12,405 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: image/jpeg,Content-Length: 4469,Date: Wed, 07 Dec 2011 19:59:12 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,transferMode.dlna.org: Interactive]]
2011-12-07 19:59:12,607 DEBUG [WebServer] Incoming connection from /192.168.0.2:45093
2011-12-07 19:59:12,607 DEBUG [ResourceTransportRequestHandler] GET /resource/266/COVER_IMAGE HTTP/1.1, headers = [transferMode.dlna.org: Interactive,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:12,607 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:12,608 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:12,608 DEBUG [ResourceTransportRequestHandler] Request for resource 266 and type 'COVER_IMAGE' received
2011-12-07 19:59:12,608 DEBUG [CoverImageDAOImpl] Reading a CoverImage (id = 266)
2011-12-07 19:59:12,609 DEBUG [CoverImageRetrievalStrategy] Retrieving Cover image with id 266
2011-12-07 19:59:12,609 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 1354
2011-12-07 19:59:12,609 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-12-07 19:59:12,609 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: image/jpeg,Content-Length: 1354,Date: Wed, 07 Dec 2011 19:59:12 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,transferMode.dlna.org: Interactive]]
2011-12-07 19:59:16,905 DEBUG [WebServer] Incoming connection from /192.168.0.2:45094
2011-12-07 19:59:16,905 DEBUG [ResourceTransportRequestHandler] HEAD /resource/1/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO$2 HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:16,905 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:16,906 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:16,906 DEBUG [ResourceTransportRequestHandler] Request for resource 1 and type 'MEDIA_ITEM' received
2011-12-07 19:59:16,907 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1 (local)
2011-12-07 19:59:16,907 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1)
2011-12-07 19:59:16,908 DEBUG [VideoDAOImpl] Reading a Video (id = 1)
2011-12-07 19:59:16,909 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1, format AVC_TS_MP_SD_AC3_ISO and profile Sony Bravia TV
2011-12-07 19:59:16,909 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv
2011-12-07 19:59:16,909 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv: AVC_TS_MP_SD_AC3_ISO
2011-12-07 19:59:16,910 DEBUG [ResourceTransportRequestHandler] Sending HEAD response back
2011-12-07 19:59:16,910 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Wed, 07 Dec 2011 19:59:16 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,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]]
2011-12-07 19:59:16,921 DEBUG [WebServer] Incoming connection from /192.168.0.2:45095
2011-12-07 19:59:16,923 DEBUG [ResourceTransportRequestHandler] GET /resource/1/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO$2 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-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:16,923 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:16,924 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:16,925 DEBUG [ResourceTransportRequestHandler] Request for resource 1 and type 'MEDIA_ITEM' received
2011-12-07 19:59:16,925 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1 (local)
2011-12-07 19:59:16,926 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1)
2011-12-07 19:59:16,927 DEBUG [VideoDAOImpl] Reading a Video (id = 1)
2011-12-07 19:59:16,928 DEBUG [VideoDeliveryEngine] Delivering item '1' for client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:16,928 DEBUG [VideoDeliveryEngine] Delivering file 'The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv' using transcoding
2011-12-07 19:59:16,929 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:16,929 DEBUG [MediaItemDAOImpl] Getting file of media item 1
2011-12-07 19:59:16,931 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: C:\Users\IBM X60s\Videos\Movies\The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv
2011-12-07 19:59:16,932 DEBUG [ProcessExecutor] Starting C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i C:\Users\IBM X60s\Videos\Movies\The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv -y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec copy -map 0:0 -map 0:1 -sn -f mpegts C:\Windows\TEMP\Serviio\transcoding-temp-1-MPEG2TS.stf
2011-12-07 19:59:17,916 WARN [ProcessExecutor] Process cmd has a return code of 1! This is a possible error.
2011-12-07 19:59:17,916 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
2011-12-07 19:59:17,933 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.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:117)
at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:93)
at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:121)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:116)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:54)
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:187)
2011-12-07 19:59:24,685 DEBUG [WebServer] Incoming connection from /192.168.0.2:45096
2011-12-07 19:59:24,705 DEBUG [ResourceTransportRequestHandler] HEAD /resource/1/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO$2 HTTP/1.1, headers = [getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,transferMode.dlna.org: Streaming,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:24,705 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:24,706 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:24,706 DEBUG [ResourceTransportRequestHandler] Request for resource 1 and type 'MEDIA_ITEM' received
2011-12-07 19:59:24,706 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1 (local)
2011-12-07 19:59:24,706 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1)
2011-12-07 19:59:24,707 DEBUG [VideoDAOImpl] Reading a Video (id = 1)
2011-12-07 19:59:24,708 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 1, format AVC_TS_MP_SD_AC3_ISO and profile Sony Bravia TV
2011-12-07 19:59:24,708 DEBUG [AbstractTranscodingDeliveryEngine] Getting media info for transcoded version of file The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv
2011-12-07 19:59:24,708 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv: AVC_TS_MP_SD_AC3_ISO
2011-12-07 19:59:24,708 DEBUG [ResourceTransportRequestHandler] Sending HEAD response back
2011-12-07 19:59:24,708 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Wed, 07 Dec 2011 19:59:24 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,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]]
2011-12-07 19:59:24,723 DEBUG [WebServer] Incoming connection from /192.168.0.2:45097
2011-12-07 19:59:24,724 DEBUG [ResourceTransportRequestHandler] GET /resource/1/MEDIA_ITEM/AVC_TS_MP_SD_AC3_ISO$2 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-46NX713";,X-AV-Client-Info: av=5.0; cn="Sony Corporation"; mn="BRAVIA KDL-46NX713"; mv="1.7";,Host: 192.168.0.3:8895]]
2011-12-07 19:59:24,724 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:24,725 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:24,725 DEBUG [ResourceTransportRequestHandler] Request for resource 1 and type 'MEDIA_ITEM' received
2011-12-07 19:59:24,725 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 1 (local)
2011-12-07 19:59:24,725 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 1)
2011-12-07 19:59:24,726 DEBUG [VideoDAOImpl] Reading a Video (id = 1)
2011-12-07 19:59:24,727 DEBUG [VideoDeliveryEngine] Delivering item '1' for client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:24,727 DEBUG [VideoDeliveryEngine] Delivering file 'The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv' using transcoding
2011-12-07 19:59:24,727 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.0.2, Profile=Sony Bravia TV'
2011-12-07 19:59:24,727 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.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:117)
at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:93)
at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:121)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:116)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:54)
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:187)
2011-12-07 19:59:28,269 DEBUG [WebServer] Incoming connection from /192.168.0.2:45098
2011-12-07 19:59:28,269 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.2:
2011-12-07 19:59:28,269 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:28,476 DEBUG [ContentDirectory] Browse() called for renderer KDL-46NX713 (profile 9) 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 =
2011-12-07 19:59:28,477 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-12-07 19:59:28,486 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:59:28,576 DEBUG [WebServer] Incoming connection from /192.168.0.2:45099
2011-12-07 19:59:28,590 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.0.2:
2011-12-07 19:59:28,590 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.0.2
2011-12-07 19:59:28,800 DEBUG [ContentDirectory] Browse() called for renderer KDL-46NX713 (profile 9) 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 =
2011-12-07 19:59:28,800 DEBUG [LocalContentCacheDecorator] Found entry in the cache (local_default), returning it
2011-12-07 19:59:28,804 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2011-12-07 19:59:35,906 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2011-12-07 19:59:39,599 INFO [ServiioConsole] Leaving Serviio Console
2011-12-07 19:59:39,614 DEBUG [ActionsServerResource] Action with name 'exitServiio' was requested
2011-12-07 19:59:39,615 DEBUG [RepositoryView] Instructing LibraryCheckingThread to stop
2011-12-07 19:59:39,668 INFO [LibraryAdditionsCheckerThread] Finished looking for newly added files
2011-12-07 19:59:39,769 INFO [LibraryUpdatesCheckerThread] Finished looking for updates to currently shared files
2011-12-07 19:59:39,856 INFO [FeedUpdaterThread] Finished looking for online feed information
2011-12-07 19:59:39,956 INFO [PluginCompilerThread] Finished looking for plugins
2011-12-07 19:59:40,057 DEBUG [DiscoveryManager] UPNP device c5db91ac-54f5-3daa-b7ba-6fea634c26b1 (192.168.0.3) is unavailable
2011-12-07 19:59:40,058 INFO [EventDispatcher] Leaving EventDispatcher
2011-12-07 19:59:40,058 INFO [DiscoveryAdvertisementNotifier] Leaving DiscoveryAdvertisementNotifier
2011-12-07 19:59:40,058 DEBUG [WebServer] Socket closed
2011-12-07 19:59:40,059 INFO [WebServer] WebServer shutting down
2011-12-07 19:59:40,059 INFO [EventSubscriptionExpirationChecker] Leaving EventSubscriptionExpirationChecker, removing all event subscriptions
2011-12-07 19:59:42,088 INFO [RendererExpirationChecker] Leaving RendererExpirationChecker
2011-12-07 19:59:42,821 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP byebye using interface net3 (Intel(R) PRO/Wireless 3945ABG Network Connection) and address 192.168.0.3, timeout = 0
2011-12-07 19:59:42,821 INFO [DiscoverySSDPMessageListener] Leaving DiscoverySSDPMessageListener
2011-12-07 19:59:42,821 INFO [DatabaseManager] Shutting down database
2011-12-07 19:59:42,821 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2011-12-07 19:59:42,821 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2011-12-07 19:59:42,821 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2011-12-07 19:59:42,821 DEBUG [DBConnectionPool] Closed connection for pool Serviio DB Pool
2011-12-07 19:59:46,643 DEBUG [DatabaseManager] DB shutdown returned: Derby system shutdown.
2011-12-07 19:59:46,643 INFO [AbstractTranscodingDeliveryEngine] Cleaning transcode engine and its data
2011-12-07 19:59:46,643 DEBUG [AbstractTranscodingDeliveryEngine] Deleting temporary transcoded files from: C:\Windows\TEMP\Serviio
2011-12-07 19:59:46,643 DEBUG [AbstractTranscodingDeliveryEngine] Deleted file C:\Windows\TEMP\Serviio\transcoding-temp-1-MPEG2TS.stf: true

Other file types play just fine, like AVI's for example. Thanks for your help. Jon.

PS: The TV has a green light next to it but the PC has a red light; although like I note above AVI files play fine so there must be a connection.

[Illico]

Could you retry locally in a windows terminal this command line:
C:\Program Files\Serviio\bin\\..\lib/ffmpeg.exe -i "C:\Users\IBM X60s\Videos\Movies\The Ides of March (2011) OSCAR DVD SCREENER-P2P.mkv" -y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec copy -map 0:0 -map 0:1 -sn -f mpegts C:\Windows\TEMP\Serviio\transcoding-temp-1-MPEG2TS.stf
A post here the ffmpeg log result

Hi Zip,
Sorry to but in on this but I am getting a similar problem (using Panasonic Viera profile).
I am able to play "most" mkv movies but with one or two I get this same error . Here is the debug output for one of the mkv movies I have:

2011-12-14 12:31:58,099 DEBUG [AbstractTranscodingDeliveryEngine] No suitable transcoding job exists yet, start one for client 'IPAddress=/192.xxx.xxx.xxx, Profile=Panasonic Viera'
2011-12-14 12:31:58,099 DEBUG [MediaItemDAOImpl] Getting file of media item 943
2011-12-14 12:31:58,099 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: /Movie-Store/War Movie Collection/Gunga Din (1939).mkv
2011-12-14 12:31:58,099 DEBUG [ProcessExecutor] Starting ffmpeg -i /Movie-Store/War Movie Collection/Gunga Din (1939).mkv -y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec copy -map 0:0 -map 0:1 -sn -f mpegts /Movie-Store/Transcoded_files/Serviio/transcoding-temp-943-MPEG2TS.stf
2011-12-14 12:31:58,142 WARN [ProcessExecutor] Process ffmpeg has a return code of 1! This is a possible error.
2011-12-14 12:31:58,142 DEBUG [TranscodingJobListener] Transcoding finished; successful: false
2011-12-14 12:31:58,601 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.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:117)
at org.serviio.delivery.resource.AbstractDeliveryEngine.deliver(AbstractDeliveryEngine.java:93)
at org.serviio.delivery.MediaResourceRetrievalStrategy.retrieveResource(MediaResourceRetrievalStrategy.java:121)
at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:116)
at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:54)
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:187)

I executed ffmpeg with comand:

ffmpeg -i "/Movie-Store/War Movie Collection/Gunga Din (1939).mkv "-y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec copy -map 0:0 -map 0:1 -sn -f mpegts /Movie-Store/Transcoded_files/Serviio/transcoding-temp-943-MPEG2TS.stf

And the result:

FFmpeg version UNKNOWN, Copyright (c) 2000-2011 the FFmpeg developers
built on Mar 20 2011 21:32:23 with gcc 4.3.2
configuration: --enable-pthreads --disable-shared --enable-static --enable-gpl --enable-libx264 --enable-libmp3lame
libavutil 50.36. 0 / 50.36. 0
libavcore 0.16. 1 / 0.16. 1
libavcodec 52.108. 0 / 52.108. 0
libavformat 52.93. 0 / 52.93. 0
libavdevice 52. 2. 3 / 52. 2. 3
libavfilter 1.74. 0 / 1.74. 0
libswscale 0.12. 0 / 0.12. 0
[matroska,webm @ 0x8c87560] Estimating duration from bitrate, this may be inaccurate

Seems stream 0 codec frame rate differs from container frame rate: 180000.00 (180000/1) -> 25.00 (25/1)
Input #0, matroska,webm, from '/Movie-Store/War Movie Collection/Gunga Din (1939).mkv':
Duration: 01:52:06.88, start: 0.000000, bitrate: N/A
Stream #0.0(eng): Video: h264, yuv420p, 656x480 [PAR 1:1 DAR 41:30], 25 fps, 25 tbr, 1k tbn, 180k tbc
Stream #0.1: Audio: aac, 48000 Hz, stereo, s16
[mpegts @ 0x8cc2aa0] muxrate VBR, pcr every 100 pkts, sdt every 200, pat/pmt every 40 pkts
Output #0, mpegts, to '/Movie-Store/Transcoded_files/Serviio/transcoding-temp-943-MPEG2TS.stf':
Metadata:
encoder : Lavf52.93.0
Stream #0.0(eng): Video: libx264, yuv420p, 656x480 [PAR 1:1 DAR 41:30], q=2-31, 90k tbn, 1k tbc
Stream #0.1: Audio: aac, 48000 Hz, stereo
Stream mapping:
Stream #0.0 -> #0.0
Stream #0.1 -> #0.1
Press [q] to stop encoding
[mpegts @ 0x8cc2aa0] Application provided invalid, non monotonically increasing dts to muxer in stream 0: -7200 >= -7200
av_interleaved_write_frame(): Operation not permitted

I dont know why ffmpeg says unknown version. Should I be worried?:

MainStore:/opt/serviio/log# ffmpeg -version
FFmpeg version UNKNOWN, Copyright (c) 2000-2011 the FFmpeg developers
built on Mar 20 2011 21:32:23 with gcc 4.3.2
configuration: --enable-pthreads --disable-shared --enable-static --enable-gpl --enable-libx264 --enable-libmp3lame
libavutil 50.36. 0 / 50.36. 0
libavcore 0.16. 1 / 0.16. 1
libavcodec 52.108. 0 / 52.108. 0
libavformat 52.93. 0 / 52.93. 0
libavdevice 52. 2. 3 / 52. 2. 3
libavfilter 1.74. 0 / 1.74. 0
libswscale 0.12. 0 / 0.12. 0
FFmpeg UNKNOWN
libavutil 50.36. 0 / 50.36. 0
libavcore 0.16. 1 / 0.16. 1
libavcodec 52.108. 0 / 52.108. 0
libavformat 52.93. 0 / 52.93. 0
libavdevice 52. 2. 3 / 52. 2. 3
libavfilter 1.74. 0 / 1.74. 0
libswscale 0.12. 0 / 0.12. 0

I got the ffmpeg from here : http://www.readynas.com/forum/viewtopic.php?f=48&t=45661
As far as I can see it is ffmpeg v 1.2

FYI, my jre is:

  Code:

MainStore:/opt/serviio/log# java -version
java version "1.6.0_07"
Java(TM) SE Runtime Environment (build 1.6.0_07-b06)
Java HotSpot(TM) Client VM (build 10.0-b23, mixed mode, sharing)

Anything else I can do to help, just ask and I will try and accommodate

[zip]

0.6.1 will ship a patched FFmpeg 0.9 (released this week) that should fix this hopefully.

Thanks zip. Excellent news.
But a bit confused: Will the patch include a new ffmpeg binary for ReadyNAS? or is it an ffmpeg invokation patch to a serviio script(s).
The version of ffmpeg I have is a port specifically for the etc/debian.
Thanks again zip your work is so much appreciated.

[zip]

for Linux OSes you'll have to compile the FFmpeg from provided sources (will be able to download on serviio.org)

o:
That's a problem for me.
I dont currently have a development environment setup on my NAS.
Can you be a bit more specific as to what in ffmpeg is being fixed. For example, should I try to find out if someone has already done the ffmpeg port for the version of ffmpeg you are refering to for the ReadyNas Pro (x64) machine?
Thanks again.

[zip]

Mostly it's this patch: https://ffmpeg.org/trac/ffmpeg/ticket/177

Ah. So basically, I will need to get 0.9 working on the ReadyNas in oder to fix this.
OK. No issues.....well sort of...I am just in the process of debootstraping a dev kit onto my nas. I have been wanting to do this for a while anyway.

[zip]

0.9 with utils.c patch applied. They say it was fixed but not for this case.

ok. cool. Look forward to seeing your patch. In the meantime I will try and get my dev kit up and running.
Best
Berny

Hey Petr, I am up, running ready and waiting

I just got my dev environ setup on my ReadyNAS, thought I would try and compile up the latest dist ffmpeg to see how I go. Success:
# ./ffmpeg -version
FFmpeg version 0.6.1, Copyright (c) 2000-2010 the FFmpeg developers
built on Dec 14 2011 22:07:52 with gcc 4.1.2 20061115 (prerelease) (Debian 4.1.1-21)
configuration:
libavutil 50.15. 1 / 50.15. 1
libavcodec 52.72. 2 / 52.72. 2
libavformat 52.64. 2 / 52.64. 2
libavdevice 52. 2. 0 / 52. 2. 0
libswscale 0.11. 0 / 0.11. 0

Man I love Linux!

[zip]

where did you get the sources? it's 1 year old (current version it 0.9).

Yeah, I realized that. I am currently battling with 0.9. I have got it compiled but not with rtmpdump as I cant get that to compile on my Linux at all!
However, I have got x264 done and mp3lame.
But I am seeing a wierd error: ./ffmpeg: error while loading shared libraries: libmp3lame.so.0: cannot open shared object file: No such file or directory
Yet I have done a make install in the libmp3lame build. I see the shared lib in /usr/local/lib but ffmpeg cant see it. Do you knw if I should have some LIBDIR env setup?
I figure, once I get this compiled, I will be ready to move forward with your patch.
I compiled ffmpeg with flags: --disable-yasm --enable-pthreads --disable-shared --enable-static --enable-gpl --enable-libx264 --enable-libmp3lame
since this is the flags used in my current ffmpeg that works fine. Except I had to use --disable-yasm. I have yasm installed but the ffmpeg configure script says it cant use it.
Your dealing with an aged Unix hack. Getting used to Linux is heavy going for me with so many distros and so many packages to get used to. Such is life

[zip]

did you read this? http://wiki.serviio.org/doku.php?id=build_ffmpeg_linux