Slow browser / folder
I've a problem with Serviio on my Synollogy. It is very slow on browsing folders. Most of the time the LG TV (DLNA) gives an error during browsing and i have to retry it 2 times.
I've done a debug trace (attached), and it looks like a slow "FolderDAOImpl" function. it spends there 4-7 seconds each time.
The trace part:
- Code:
2017-05-07 21:04:00,959 DEBUG [ContentDirectory] Browse() called for renderer LG TV (profile 8) with parameters: objectID = 0, browseFlag = BrowseDirectChildren, 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@protection,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID, res@dlna:cleartextSize, startIndex = 0, count = 24, sortCriteria =
2017-05-07 21:04:00,961 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2017-05-07 21:04:01,029 DEBUG [VideoDAOImpl] Retrieving number of video initials [No_Restriction]
2017-05-07 21:04:03,324 DEBUG [RendererSearchSender] Searching for Renderer devices
2017-05-07 21:04:03,337 DEBUG [RendererSearchSender$RendererSearchWorker] Multicasting SSDP M-SEARCH using interface eth0 (eth0) and address 192.168.123.10, timeout = 0
2017-05-07 21:04:03,340 DEBUG [RendererSearchSender$RendererSearchWorker] Sending 1 'm-search' messages
2017-05-07 21:04:03,345 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.123.10:41338s
2017-05-07 21:04:03,352 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.123.100:37904/MediaRenderer1.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2017-05-07 21:04:03,352 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.123.10:41338s
2017-05-07 21:04:03,368 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target urn:schemas-upnp-org:device:MediaRenderer:1 from address /192.168.123.10:41338s
2017-05-07 21:04:03,368 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 21f66c00-1dd2-11b2-bc80-dac9527e7212 from address 192.168.123.100
2017-05-07 21:04:03,375 DEBUG [RendererManager] Retrieve device description from http://192.168.123.100:37904/MediaRenderer1.xml
2017-05-07 21:04:03,378 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.123.100:37904/MediaRenderer1.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2017-05-07 21:04:03,395 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 21f66c00-1dd2-11b2-bc80-dac9527e7212 from address 192.168.123.100
2017-05-07 21:04:03,400 DEBUG [RendererSearchSender$RendererSearchWorker] Received search response: location: http://192.168.123.100:37904/MediaRenderer1.xml, st: urn:schemas-upnp-org:device:MediaRenderer:1
2017-05-07 21:04:03,417 DEBUG [RendererSearchSender$RendererSearchWorker] Received a valid M-SEARCH response from Renderer 21f66c00-1dd2-11b2-bc80-dac9527e7212 from address 192.168.123.100
2017-05-07 21:04:03,882 DEBUG [RendererManager] Retrieve device description from http://192.168.123.100:37904/MediaRenderer1.xml
2017-05-07 21:04:04,423 DEBUG [RendererManager] Retrieve device description from http://192.168.123.100:37904/MediaRenderer1.xml
2017-05-07 21:04:06,130 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO [No_Restriction]
2017-05-07 21:04:06,225 DEBUG [VideoDAOImpl] Retrieving number of 10 last added videos
2017-05-07 21:04:06,228 DEBUG [VideoDAOImpl] Retrieving number of videos of type 0 [No_Restriction]
2017-05-07 21:04:09,364 DEBUG [RendererSearchSender] Finished searching for Renderer devices
2017-05-07 21:04:11,921 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2017-05-07 21:04:14,651 DEBUG [ServiioHttpService] Incoming request from /192.168.123.100:50522: POST /serviceControl HTTP/1.1, headers = [HOST: 192.168.123.10:8895,CONTENT-LENGTH: 727,CONTENT-TYPE: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse",USER-AGENT: Linux/2.6.35 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.6.0]]
2017-05-07 21:04:14,661 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.100
2017-05-07 21:04:14,707 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.123.100
2017-05-07 21:04:14,711 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.100
2017-05-07 21:04:14,737 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.100
2017-05-07 21:04:14,876 DEBUG [ContentDirectory] Browse() called for renderer LG TV (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@protection,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID, res@dlna:cleartextSize, startIndex = 0, count = 0, sortCriteria =
2017-05-07 21:04:14,878 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2017-05-07 21:04:14,891 DEBUG [RepositoryDAOImpl] Retrieving number of repositories for VIDEO [No_Restriction]
2017-05-07 21:04:14,985 DEBUG [LocalContentCacheDecorator] Stored entry in the cache (local_default), returning it
2017-05-07 21:04:15,107 DEBUG [ServiceControlRequestHandler] Returning OK SOAP message
2017-05-07 21:04:15,126 DEBUG [ServiioHttpService] Incoming request from /192.168.123.100:50523: POST /serviceControl HTTP/1.1, headers = [HOST: 192.168.123.10:8895,CONTENT-LENGTH: 734,CONTENT-TYPE: text/xml; charset="utf-8",SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse",USER-AGENT: Linux/2.6.35 UPnP/1.0 DLNADOC/1.50 INTEL_NMPR/2.0 LGE_DLNA_SDK/1.6.0]]
2017-05-07 21:04:15,131 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.100
2017-05-07 21:04:15,157 DEBUG [ServiceControlRequestHandler] ServiceControl request received for action '"urn:schemas-upnp-org:service:ContentDirectory:1#Browse"' from 192.168.123.100
2017-05-07 21:04:15,161 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.100
2017-05-07 21:04:15,183 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.123.100
2017-05-07 21:04:15,313 DEBUG [ContentDirectory] Browse() called for renderer LG TV (profile 8) with parameters: objectID = V_F, browseFlag = BrowseDirectChildren, 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@protection,res@bitrate,res@resolution,res@protocolInfo,res@nrAudioChannels,res@sampleFrequency,upnp:albumArtURI,upnp:albumArtURI@dlna:profileID, res@dlna:cleartextSize, startIndex = 0, count = 24, sortCriteria =
2017-05-07 21:04:15,316 DEBUG [AccessGroupDAOImpl] Reading a AccessGroup (id = 1)
2017-05-07 21:04:15,334 DEBUG [RepositoryDAOImpl] Retrieving list of Repositories for VIDEO (from=0, count=24) [No_Restriction]
2017-05-07 21:04:15,887 DEBUG [CoverImageDAOImpl] Reading a CoverImage for repository (id = 2)
2017-05-07 21:04:18,064 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
2017-05-07 21:04:25,031 DEBUG [CoverImageDAOImpl] Reading a CoverImage for repository (id = 3)
2017-05-07 21:04:26,369 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
2017-05-07 21:04:32,970 DEBUG [CoverImageDAOImpl] Reading a CoverImage for repository (id = 4)
2017-05-07 21:04:34,085 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
2017-05-07 21:04:36,273 DEBUG [FeedUpdaterWorker] Checking for new and expired online resources
2017-05-07 21:04:36,274 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2017-05-07 21:04:41,802 DEBUG [CoverImageDAOImpl] Reading a CoverImage for repository (id = 5)
2017-05-07 21:04:42,833 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
2017-05-07 21:04:49,059 DEBUG [CoverImageDAOImpl] Reading a CoverImage for repository (id = 6)
2017-05-07 21:04:50,363 DEBUG [FolderDAOImpl] Getting number of VIDEO sub-folders and media items in folder 'root' (filter: ALL) [No_Restriction]
i started the browse on 21:04:00, at 21:04:15 the first error on the TV, and i had to click the folder icon again.
any ideas? there are about 500 video files, neatly in folders.
Thanks.
S