FAQ  •  Register  •  Login

Slow browser / folder

<<

stappel

Serviio newbie

Posts: 5

Joined: Fri May 12, 2017 11:26 am

Post Fri May 12, 2017 11:36 am

Slow browser / folder

Hi,
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
Attachments
serviio.log.zip
(64.95 KiB) Downloaded 209 times
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Fri May 12, 2017 12:50 pm

Re: Slow browser / folder

what is the NAS spec in terms of cpu and memory?
<<

stappel

Serviio newbie

Posts: 5

Joined: Fri May 12, 2017 11:26 am

Post Fri May 12, 2017 1:08 pm

Re: Slow browser / folder

Its a Synollogy DS414.
Marvell Armada CPU, 1,33Gz, dual core.
1GB memory.

java options are set to: -Xmx512M -Xms128M -XX:+UseG1GC -XX:GCTimeRatio=1 -XX:MinHeapFreeRatio=10 -XX:MaxHeapFreeRatio=20

Using the build-in Media also shows this delay in going thru the folders.
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Fri May 12, 2017 3:35 pm

Re: Slow browser / folder

I think it's just a not very performant CPU, so the DB is not so efficient.
<<

stappel

Serviio newbie

Posts: 5

Joined: Fri May 12, 2017 11:26 am

Post Fri May 12, 2017 5:23 pm

Re: Slow browser / folder

Hmm, never had the problem with 1.6 version, there was always a delay from 10-15 seconds. i got used to that.
with 1.8 it got worse, so worse the TV gives an error message (unknown error it just says). i think it is a timeout which causes the error.
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Sat May 13, 2017 12:30 pm

Re: Slow browser / folder

1.8 potentially uses more advanced DB queries to support new features so takes a bit longer.
<<

stappel

Serviio newbie

Posts: 5

Joined: Fri May 12, 2017 11:26 am

Post Mon May 15, 2017 10:36 am

Re: Slow browser / folder

I tried version 1.6, it is giving less timeout messages. so yes it might be due to the new queries. But then Derby is known to have a bad performance on complex queries. H2 or HSQLDB might be better

Return to Serviio Support & Help

Who is online

Users browsing this forum: No registered users and 54 guests

Powered by phpBB © 2000, 2002, 2005, 2007 phpBB Group.
Designed by ST Software for PTF.