MKV and LG 47LE5500 - Invalid File

Hey guys.

When I try to play some MKV files I always get the "Invalid file" error. No idea why. Here is the info from MediaInfo and ffmpeg:


Here is complete log... there are some numbersformatexceptions for some other movie that plays fine, but no reference to the MKV movie (was testing on Wolverine.mkv)

  Code:

...
2011-10-31 09:12:05,392 ERROR [ActionNode] Cannot retrieve results of action command: Cannot execute library command for single item: For input string: "111/AttacktheBlock.avi"
org.serviio.upnp.service.contentdirectory.command.CommandExecutionException: Cannot execute library command for single item: For input string: "111/AttacktheBlock.avi"
   at org.serviio.upnp.service.contentdirectory.command.AbstractCommand.retrieveItem(AbstractCommand.java:136)
   at org.serviio.upnp.service.contentdirectory.definition.ActionNode.executeRetrieveItemAction(ActionNode.java:175)
   at org.serviio.upnp.service.contentdirectory.definition.ActionNode.retrieveDirectoryObject(ActionNode.java:80)
   at org.serviio.upnp.service.contentdirectory.ContentDirectory.Browse(ContentDirectory.java:199)
   at sun.reflect.GeneratedMethodAccessor9.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at org.serviio.upnp.protocol.soap.ServiceInvoker.invokeOperation(ServiceInvoker.java:253)
   at org.serviio.upnp.protocol.soap.ServiceInvoker.invokeService(ServiceInvoker.java:93)
   at org.serviio.upnp.webserver.ServiceControlRequestHandler.handleRequest(ServiceControlRequestHandler.java:74)
   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)
Caused by: java.lang.NumberFormatException: For input string: "111/AttacktheBlock.avi"
   at java.lang.NumberFormatException.forInputString(Unknown Source)
   at java.lang.Long.parseLong(Unknown Source)
   at java.lang.Long.parseLong(Unknown Source)
   at org.serviio.upnp.service.contentdirectory.command.AbstractListObjectsByFSHierarchyCommand.getFolderId(AbstractListObjectsByFSHierarchyCommand.java:211)
   at org.serviio.upnp.service.contentdirectory.command.AbstractListObjectsByFSHierarchyCommand.retrieveSingleItem(AbstractListObjectsByFSHierarchyCommand.java:153)
   at org.serviio.upnp.service.contentdirectory.command.AbstractCommand.retrieveItem(AbstractCommand.java:131)
   ... 14 more
2011-10-31 09:12:05,406 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F111/AttacktheBlock.avi:BrowseMetadata:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:0::8, localOnly = false
2011-10-31 09:12:05,406 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F111/AttacktheBlock.avi:BrowseMetadata:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:0::8
2011-10-31 09:12:05,406 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache miss for V_F#FOL_R1$F111/AttacktheBlock.avi:BrowseMetadata:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:0::8
2011-10-31 09:12:05,406 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 10 elements, linked list contains 10 elements
2011-10-31 09:12:05,406 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:05,406 DEBUG [CompositeCache] local_default - Miss
2011-10-31 09:12:05,407 ERROR [ActionNode] Cannot retrieve results of action command: Cannot execute library command for single item: For input string: "111/AttacktheBlock.avi"
org.serviio.upnp.service.contentdirectory.command.CommandExecutionException: Cannot execute library command for single item: For input string: "111/AttacktheBlock.avi"
   at org.serviio.upnp.service.contentdirectory.command.AbstractCommand.retrieveItem(AbstractCommand.java:136)
   at org.serviio.upnp.service.contentdirectory.definition.ActionNode.executeRetrieveItemAction(ActionNode.java:175)
   at org.serviio.upnp.service.contentdirectory.definition.ActionNode.retrieveDirectoryObject(ActionNode.java:80)
   at org.serviio.upnp.service.contentdirectory.ContentDirectory.Browse(ContentDirectory.java:199)
   at sun.reflect.GeneratedMethodAccessor9.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at org.serviio.upnp.protocol.soap.ServiceInvoker.invokeOperation(ServiceInvoker.java:253)
   at org.serviio.upnp.protocol.soap.ServiceInvoker.invokeService(ServiceInvoker.java:93)
   at org.serviio.upnp.webserver.ServiceControlRequestHandler.handleRequest(ServiceControlRequestHandler.java:74)
   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)
Caused by: java.lang.NumberFormatException: For input string: "111/AttacktheBlock.avi"
   at java.lang.NumberFormatException.forInputString(Unknown Source)
   at java.lang.Long.parseLong(Unknown Source)
   at java.lang.Long.parseLong(Unknown Source)
   at org.serviio.upnp.service.contentdirectory.command.AbstractListObjectsByFSHierarchyCommand.getFolderId(AbstractListObjectsByFSHierarchyCommand.java:211)
   at org.serviio.upnp.service.contentdirectory.command.AbstractListObjectsByFSHierarchyCommand.retrieveSingleItem(AbstractListObjectsByFSHierarchyCommand.java:153)
   at org.serviio.upnp.service.contentdirectory.command.AbstractCommand.retrieveItem(AbstractCommand.java:131)
   ... 14 more
2011-10-31 09:12:08,508 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F66:BrowseMetadata:upnp:class:0:0::8, localOnly = false
2011-10-31 09:12:08,508 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F66:BrowseMetadata:upnp:class:0:0::8
2011-10-31 09:12:08,508 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache miss for V_F#FOL_R1$F66:BrowseMetadata:upnp:class:0:0::8
2011-10-31 09:12:08,508 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 10 elements, linked list contains 10 elements
2011-10-31 09:12:08,508 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,508 DEBUG [CompositeCache] local_default - Miss
2011-10-31 09:12:08,509 DEBUG [CompositeCache] Updating memory cache
2011-10-31 09:12:08,509 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,509 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,509 DEBUG [CompositeCache] No auxilliary cache to update
2011-10-31 09:12:08,524 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F66:BrowseDirectChildren:upnp:class:0:100::8, localOnly = false
2011-10-31 09:12:08,524 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F66:BrowseDirectChildren:upnp:class:0:100::8
2011-10-31 09:12:08,524 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache miss for V_F#FOL_R1$F66:BrowseDirectChildren:upnp:class:0:100::8
2011-10-31 09:12:08,524 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 11 elements, linked list contains 11 elements
2011-10-31 09:12:08,524 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,524 DEBUG [CompositeCache] local_default - Miss
2011-10-31 09:12:08,529 DEBUG [CompositeCache] Updating memory cache
2011-10-31 09:12:08,529 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,529 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,529 DEBUG [CompositeCache] No auxilliary cache to update
2011-10-31 09:12:08,550 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8, localOnly = false
2011-10-31 09:12:08,550 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:08,550 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache miss for V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:08,550 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 12 elements, linked list contains 12 elements
2011-10-31 09:12:08,551 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,551 DEBUG [CompositeCache] local_default - Miss
2011-10-31 09:12:08,556 DEBUG [CompositeCache] Updating memory cache
2011-10-31 09:12:08,556 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,556 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,556 DEBUG [CompositeCache] No auxilliary cache to update
2011-10-31 09:12:08,586 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8, localOnly = false
2011-10-31 09:12:08,586 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:08,586 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache hit for V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:08,586 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 13 elements, linked list contains 13 elements
2011-10-31 09:12:08,586 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:08,586 DEBUG [CompositeCache] local_default - Memory cache hit
2011-10-31 09:12:10,451 DEBUG [ShrinkerThread] Shrinking memory cache for: thumbnails
2011-10-31 09:12:10,451 DEBUG [ShrinkerThread] Keys size: 0
2011-10-31 09:12:10,451 DEBUG [ShrinkerThread] Shrinking memory cache for: online_feeds
2011-10-31 09:12:10,451 DEBUG [ShrinkerThread] Keys size: 0
2011-10-31 09:12:13,308 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8, localOnly = false
2011-10-31 09:12:13,308 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:13,308 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache hit for V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:13,309 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 13 elements, linked list contains 13 elements
2011-10-31 09:12:13,309 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:13,309 DEBUG [CompositeCache] local_default - Memory cache hit
2011-10-31 09:12:13,339 DEBUG [CompositeCache] Removed All keys from the memory cache.
2011-10-31 09:12:13,400 DEBUG [CompositeCache] Removed All keys from the memory cache.
2011-10-31 09:12:27,976 DEBUG [CompositeCache] get: key = V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8, localOnly = false
2011-10-31 09:12:27,976 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:27,976 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache hit for V_F#FOL_R1$F66:BrowseDirectChildren:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:54::8
2011-10-31 09:12:27,976 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 13 elements, linked list contains 13 elements
2011-10-31 09:12:27,976 DEBUG [LRUMemoryCache] verifycache: checking linked list by key
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [LRUMemoryCache] verifycache: checking linked list by value
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [LRUMemoryCache] verifycache: checking via keysets!
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [DoubleLinkedList] returning first node
2011-10-31 09:12:27,976 DEBUG [CompositeCache] local_default - Memory cache hit
2011-10-31 09:12:28,507 DEBUG [CompositeCache] Removed All keys from the memory cache.
2011-10-31 09:12:28,508 DEBUG [CompositeCache] Removed All keys from the memory cache.
2011-10-31 09:12:40,452 DEBUG [ShrinkerThread] Shrinking memory cache for: thumbnails
2011-10-31 09:12:40,452 DEBUG [ShrinkerThread] Keys size: 0
2011-10-31 09:12:40,452 DEBUG [ShrinkerThread] Shrinking memory cache for: online_feeds
2011-10-31 09:12:40,452 DEBUG [ShrinkerThread] Keys size: 0


I tried to play it with LG TV profile as well as with Generic DLNA profile. Same result.

Any ideas? :S
Thanks.

[zip]

MIght be because the file uses Header stripping (compression), search the forum on how to solve that (will probably have to reencode or remux the video)

[Illico]

viewtopic.php?f=6&t=1132

I will give this a try then and report back if that solved it.

Thank you both.

Hey guys.

No luck with remuxing. But I did screw up logging in my original post, it is not the proper DEBUG. I have enabled that and found an interesting error for which I can not find any answers. Here is the section of the log...

  Code:

2011-11-04 21:04:27,336 ERROR [ResourceTransportRequestHandler] Error while processing resource, sending back 500 error. Message: Cannot create transcoding folder: D:\Temp\Serviio
java.io.IOException: Cannot create transcoding folder: D:\Temp\Serviio
   at org.serviio.delivery.resource.AbstractTranscodingDeliveryEngine.prepareTranscodingFolder(AbstractTranscodingDeliveryEngine.java:311)
   at org.serviio.delivery.resource.AbstractTranscodingDeliveryEngine.retrieveTranscodedResource(AbstractTranscodingDeliveryEngine.java:107)
   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-11-04 21:04:27,336 DEBUG [MediaItemDAOImpl] Getting file of media item 290
2011-11-04 21:04:27,338 DEBUG [FFMPEGWrapper] Invoking FFmpeg to transcode video file: D:\Filmi\Wolverine\Wolverine.mkv
2011-11-04 21:04:27,338 DEBUG [ProcessExecutor] Starting C:\Program Files (x86)\Serviio\bin\\..\lib/ffmpeg.exe -i D:\Filmi\Wolverine\Wolverine.mkv -y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec libmp3lame -ab 192k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts D:\Temp\Serviio\transcoding-temp-290-MPEG2TS.stf
2011-11-04 21:04:27,838 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Wolverine.mkv: AVC_TS_MP_HD_MPEG1_L3_ISO
2011-11-04 21:04:27,838 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-11-04 21:04:27,838 DEBUG [MediaItemDAOImpl] Marking MediaItem (id = 290) as read
2011-11-04 21:04:27,843 DEBUG [CompositeCache] Removed All keys from the memory cache.
2011-11-04 21:04:27,843 DEBUG [LocalContentCacheDecorator] Cleared cache (local_resetafterplay)
2011-11-04 21:04:27,844 DEBUG [ResourceTransportRequestHandler] Creating stream entity with length: 50000000000
2011-11-04 21:04:27,844 DEBUG [ResourceTransportRequestHandler] Creating entity with chunked transfer
2011-11-04 21:04:27,844 DEBUG [ResourceTransportRequestHandler] Sending file back
2011-11-04 21:04:27,844 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 200 OK, headers = [[Content-Type: video/mpeg,Date: Fri, 04 Nov 2011 20:04:27 GMT,Server: Windows 7, UPnP/1.0 DLNADOC/1.50, Serviio/0.6.0.1,Cache-control: no-cache,transferMode.dlna.org: Streaming]]
2011-11-04 21:04:27,859 DEBUG [WebServer] I/O error: Connection reset by peer: socket write error
2011-11-04 21:04:27,860 DEBUG [WebServer] Incoming connection from /192.168.1.70:53162
2011-11-04 21:04:27,860 DEBUG [ResourceTransportRequestHandler] GET /resource/290/MEDIA_ITEM/AVC_TS_MP_HD_MPEG1_L3_ISO$0 HTTP/1.1, headers = [Host: 192.168.1.64:8895,Pragma: no-cache,Cache-Control: no-cache,Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, image/png, text/html, text/xml, text/plain, application/json, application/atom+xml, */*,Accept-Language: ko-kr, ko;q=0.8, en-us;q=0.5, en;q=0.3,Connection: Close,Range: bytes=4611686018427371520-,User-Agent: Mozilla/5.0 (compatible; MSIE 8.0; Windows NT 5.1; LG_UA; AD_LOGON=LGE.NET; .NET CLR 2.0.50727; .NET CLR 3.0.04506.30; .NET CLR 3.0.04506.648; LG_UA; AD_LOGON=LGE.NET; LG NetCast.TV-2010)]]
2011-11-04 21:04:27,860 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.70
2011-11-04 21:04:27,861 DEBUG [ResourceTransportRequestHandler] Resource request accepted. Using client 'IPAddress=/192.168.1.70, Profile=LG TV / player'
2011-11-04 21:04:27,861 DEBUG [ResourceTransportRequestHandler] Request for resource 290 and type 'MEDIA_ITEM' received
2011-11-04 21:04:27,861 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 290 (local)
2011-11-04 21:04:27,861 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 290)
2011-11-04 21:04:27,861 DEBUG [VideoDAOImpl] Reading a Video (id = 290)
2011-11-04 21:04:27,862 DEBUG [VideoDeliveryEngine] Delivering item '290' for client 'IPAddress=/192.168.1.70, Profile=LG TV / player'
2011-11-04 21:04:27,862 DEBUG [VideoDeliveryEngine] Delivering file 'Wolverine.mkv' using transcoding
2011-11-04 21:04:27,862 DEBUG [AbstractTranscodingDeliveryEngine] A suitable transcoding job already exists, re-use it for client 'IPAddress=/192.168.1.70, Profile=LG TV / player'
2011-11-04 21:04:27,862 DEBUG [VideoDeliveryEngine] Found Format profile for transcoded file Wolverine.mkv: AVC_TS_MP_HD_MPEG1_L3_ISO
2011-11-04 21:04:27,862 DEBUG [AbstractTranscodingDeliveryEngine] Sending transcoding stream
2011-11-04 21:04:27,862 DEBUG [ResourceTransportRequestHandler] Unsupported range request, sending back 416
2011-11-04 21:04:27,862 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 416 Requested Range Not Satisfiable, headers = [[Content-Type: video/mpeg]]
2011-11-04 21:04:42,629 DEBUG [WebServer] Incoming connection from /192.168.1.70:53164
2011-11-04 21:04:42,630 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.1.70:
2011-11-04 21:04:42,630 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.70
2011-11-04 21:04:42,632 DEBUG [ContentDirectory] Browse() called for renderer LG TV / player (profile 8) with parameters: objectID = V_F, browseFlag = BrowseMetadata, filter = @id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency, startIndex = 0, count = 0, sortCriteria =
2011-11-04 21:04:42,632 DEBUG [CompositeCache] get: key = V_F:BrowseMetadata:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:0::8, localOnly = false
2011-11-04 21:04:42,632 DEBUG [LRUMemoryCache] getting item from cache local_default for key V_F:BrowseMetadata:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:0::8
2011-11-04 21:04:42,632 DEBUG [LRUMemoryCache] local_default: LRUMemoryCache miss for V_F:BrowseMetadata:@id,@parentID,@restricted,@childCount,dc:title,dc:creator, upnp:artist,upnp:class,dc:date,upnp:album,upnp:genre,res,res@size,res@duration,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency:0:0::8
2011-11-04 21:04:42,632 DEBUG [LRUMemoryCache] verifycache[local_default]: mapContains 23 elements, linked list contains 23 elements
2011-11-04 21:04:42,632 DEBUG [LRUMemoryCache] verifycache: checking linked list by key


Note: i did try to run this manually:

  Code:

C:\Program Files (x86)\Serviio\bin\\..\lib/ffmpeg.exe -i D:\Filmi\Wolverine\Wolverine.mkv -y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec libmp3lame -ab 192k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts D:\Temp\Serviio\transcoding-temp-290-MPEG2TS.stf


and that did produce appropriate output in the d:\temp\serviio folder, so, the answer to why serviio is unable to do it eludes me. I did have to alter that line to this though, but I think that this is not the problem as the error indicates inability to create folder/output.

  Code:

"C:\Program Files (x86)\Serviio\lib/ffmpeg.exe" -i D:\Filmi\Wolverine\Wolverine.mkv -y -vcodec copy -vbsf h264_mp4toannexb -copyts -acodec libmp3lame -ab 192k -ac 2 -map 0:0 -map 0:1 -sn -f mpegts D:\Temp\Serviio\transcoding-temp-290-MPEG2TS.stf


Any ideas?

I am sorry to post like this :S But, I had success!

What I did is this: took the manually transcoded file with extension stf, copied it to my movies folder (library) and renamed the extension to ts. The serviio recognised it and now it is playing it without problems.

So, the question is: could this be done/fixed in serviio, so that one would not have to go through this manually?

Thanks!

[Illico]

Its probably due to not enough free space on your "D:\Temp\Serviio" when ffmpeg transcode big file...could you check that?

The C drive has 100GB free, D has 400GB free.

Plenty of space either way, besides, when manually executing that script, the output destinations remained the same. Really strange.

[zip]

The folder error is not the main reason. The LG devices don't behave correctly with Serviio's transcoding due to a bug in their firmware. It's asking for bytes of the file that don't exist. I will try to have a look at a workaround for a next version, hopefully it won't break current functionality (send me a PM if you want to beta test it).

Solution: disable or delete transcoding tag in LG TV profile in your profiles xml file.

After that, I can now watch all movies that were not playing before.

Thanks to all.

[zip]

To any LG user. Do you have transcoding working with another server? if yes, capture packets of the playback in Wireshark and post it here. Thanks.

Am I right in that the LG profile is only set to transcode files with AC3 audio? If so, why? The LG TVs support AC3, its DTS that needs transcoding.

Also, why is there no audio transcoding defined? Is there just not enough of us testing to determine the right settings?

[Cerberus]

Am I right in that the LG profile is only set to transcode files with AC3 audio? If so, why? The LG TVs support AC3, its DTS that needs transcoding.

Also, why is there no audio transcoding defined? Is there just not enough of us testing to determine the right settings?

LG does not support on the fly transcoding period thats why.

That is interesting as I always thought transcoding was purely a hack anyway designed to give the illusion to the client that the file is natively of a given format.

Is it that this was the case in early implementations of DLNA but later versions support the ability of the client to know a source is transcoded?

[Cerberus]

That is interesting as I always thought transcoding was purely a hack anyway designed to give the illusion to the client that the file is natively of a given format.

Is it that this was the case in early implementations of DLNA but later versions support the ability of the client to know a source is transcoded?

nope always been the same and its not a HACK as you put it.

you can have to type of stream: Transport streams and Native streams

So what is to stop you from saying its a native stream when in fact its a transcoded stream? Is there some limitation while transcoding that means you couldn't spoof the same behaviour without transcoding the whole file first?

[Cerberus]

So what is to stop you from saying its a native stream when in fact its a transcoded stream? Is there some limitation while transcoding that means you couldn't spoof the same behaviour without transcoding the whole file first?

Not possible with native stream file is sent as a whole, but with a transport stream file it is sent in peices as its transcoded ( hense on the fly transcoding)..

What I meant was, what is the difference so far as what the client sees as obviously in both scenarios it only sees a small piece of the file at a time. What stops you from spoofing what the client is expecting to see from a normal file stream but in fact sending data being transcoded in real-time? Is it [for example] meta data that can only be generated once its fully transcoded?

Basically what I expected was a "yes, it can be done - but it would require a lot of work especially when its only for one specific client and the server would have to parse the whole file before starting to stream so its likely the client would timeout before the data started being sent".

Incidentally, getting back on topic, my LG TV (47LW550T) shows up as "MediaRenderer" rather than "LG TV / player" (which another TV in the house comes up as). So are we sure that the newer 2011 models do not support transcoding?

They obviously changed something as now you access DLNA from the input source screen as "Network" (with a little icon saying DLNA) whereas on older TVs you had to go to Menu and it showed up as My Media. Part of this change was adding DLNA renderer support, so I can stream straight from my phone.

[Cerberus]

Dont know yet have not started looking at LG devices yet and at current I only have information on 1 device and the forum posts to go on.

http://wiki.serviio.org/doku.php?id=dev ... at_support

I have created a new thread as I feel I have derailed this one well off-topic which is not helpful to anyone.
viewtopic.php?f=12&t=4612

That said the reason I originally posted in this thread is because as I understand it the 47LE5500 is the same as the 47LW550T except without the passive 3D functionality and the DVB-T2 tuner. As such its likely to have the same firmware.