Very slow EPG scrolling (1 Viewer)

[ianc]

I have a situation whereby the first time I try to scroll down through the EPG listing it scrolls very slowly (one row every 3/4 of a second). Once I scrolled down once - its fine. But first time it's painfuly slow. I've looked in the logs running in debug mode but I can't see anything to indicate this problem.

I think the problem started when I upgraded to 1.2.1 but I can't be sure. Are others experience the same issue? Any pointers on further diagnosing?



MediaPortal Version: MP 1.2.1
MediaPortal Skin: Standard
Windows Version: W7 Ultimate latest patches & SP
CPU Type: Intel Q6600
HDD: ~2 TB
Memory: 2G
Motherboard: abit ab9 Quad GT
Video Card: Asus EAH4770 PCIe
Video Card Driver: 8.771.0.0 25/08/2010
Sound Card: onboard
Sound Card AC3: Optical out
Sound Card Driver:
1. TV Card: FireDTV
1. TV Card Type: DVB-S2
1. TV Card Driver: 5.7
2. TV Card: Avermedia PCI
2. TV Card Type: DVB S
2. TV Card Driver: latest?
3. TV Card: Terratec Cinergy dual
3. TV Card Type: DVB-T
3. TV Card Driver: 1.1.0.284
4. TV Card: Videomate S350
4. TV Card Type: DVB-S
4. TV Card Driver: 1.3.9.0
MPEG2 Video Codec: SAF 4 beta22 (recomended)
MPEG2 Audio Codec: SAF 4 beta22 (recomended)
h.264 Video Codec: SAF beta22 (recommended)
Satelite/CableTV Provider: 28.2
HTPC Case:
Cooling:
Power Supply:
Remote: MCE
TV: Panasonic ax200
TV - HTPC Connection: HDMI

 

[mm1352000]

Hi ianc

Here are the log entries starting when you enter the TV section and running to when you leave the TV section:

2012-01-03 22:10:32.910375 [Debug][MPMain(1)]: Window: TvPlugin.TVHome init
2012-01-03 22:10:32.910375 [Info.][MPMain(1)]: TVHome:OnPageLoad
2012-01-03 22:10:33.004125 [Info.][MPMain(1)]: ChannelNavigator::Reload()
2012-01-03 22:10:33.004125 [Info.][MPMain(1)]: get channels from database
2012-01-03 22:10:33.644750 [Info.][MPMain(1)]: found:943 tv channels
2012-01-03 22:10:33.644750 [Info.][MPMain(1)]: TvNotify:OnNotifiesChanged
2012-01-03 22:10:33.988500 [Info.][MPMain(1)]: Done.
2012-01-03 22:10:33.988500 [Info.][MPMain(1)]: get all groups from database
2012-01-03 22:10:34.441625 [Info.][MPMain(1)]: loaded 3 tv groups
2012-01-03 22:10:34.441625 [Info.][MPMain(1)]: tv home init:Eurosport UK
2012-01-03 22:10:34.441625 [Info.][MPMain(1)]: tv home init:Eurosport UK done
2012-01-03 22:10:37.129125 [Info.][MPMain(1)]: TvNotify:LoadNotifies
2012-01-03 22:10:40.754125 [Info.][MPMain(1)]: TvNotify: 0 notifies
2012-01-03 22:10:40.988500 [Debug][MPMain(1)]: Window: TvPlugin.TVHome deinit
2012-01-03 22:10:40.988500 [Debug][MPMain(1)]: TextureManager: CleanupThumbs()
2012-01-03 22:10:41.832250 [Debug][MPMain(1)]: DialogWindow: TvPlugin.TVGuide init
2012-01-03 22:10:46.488500 [Info.][MediaScanner(18)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:46.504125 [Info.][MediaScanner(15)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:47.644750 [Info.][MPMain(1)]: TexturePacker: Loaded C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal\Cache\DefaultWide\packedgfx24.png texture:2048x2048 miplevels:1
2012-01-03 22:10:47.644750 [Info.][MPMain(1)]: TexturePacker: fontengine add texure:1995
2012-01-03 22:10:49.301000 [Info.][MediaScanner(19)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:49.660375 [Info.][MediaScanner(18)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:50.332250 [Info.][(2)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:52.035375 [Info.][MediaScanner(16)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:58.535375 [Info.][MediaScanner(17)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:58.754125 [Info.][(2)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:58.832250 [Info.][MediaScanner(16)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:59.004125 [Info.][(2)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:10:59.847875 [Info.][MediaScanner(19)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:11:00.457250 [Info.][MediaScanner(18)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:11:00.769750 [Info.][(2)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:11:00.785375 [Info.][MediaScanner(15)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:11:00.847875 [Info.][(2)]: SQLiteClient: Closing database: VideoDatabaseV5.db3
2012-01-03 22:12:43.410375 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal\Skin\DefaultWide\myHome.xml' is missing control id 200 (window property: lblDate)
2012-01-03 22:12:43.410375 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal\Skin\DefaultWide\myHome.xml' is missing control id 201 (window property: lblTime)
2012-01-03 22:12:43.410375 [Warn.][MPMain(1)]: GUIWindow:OnWindowLoaded: 'C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal\Skin\DefaultWide\myHome.xml' is missing control id 99 (window property: videoWindow)
2012-01-03 22:12:43.441625 [Debug][MPMain(1)]: Window: MediaPortal.GUI.Home.GUIHome init
2012-01-03 22:12:45.129125 [Info.][MPMain(1)]: Main: Exit requested

The most obvious thing there are the references to your video database, but having said that they are referencing different threads. I wonder whether your HDD is working hard at that time as the guide can be a little heavy on the DB.

mm

 

[ianc]

mm Thanks for the response.

It's a multiseat (up to 5) installation and the log I attached is from a client. The problem is appearing on all clients (inc the one hosting TVserver). HDD on both the client and the server are relatively quiet - only one user and not much background activity.

A couple of Q's to help me diagnose if I may
1) Is there round trip to the TVserver for each new EPG row being rendered for the first time?
2) If not - where does the client store the info - in the Video db or memory?
3) Is there a way to see if there are any indexes missing from DBs (MySQL or client side)?

 

[JackTramiel]

AW: Very slow EPG scrolling

I installed the tvserver and mysql into a ramdisk. First start takes still "long" time - one of the reasons i did that to improve it - , but then EPG is as fast as i want it to be. No stops or anything.

Sorry, not really a solution or hint for your problem. Maybe virusscan software or anything like this?

 

[ianc]

JT
Good suggestion on the Virus scanner. I turned it off - no change :-(

 

[JackTramiel]

AW: Very slow EPG scrolling

Is it also slow using a keyboard insted of the remote?

 

[ianc]

Both similar

 

[mm1352000]

Hi again

It's a multiseat (up to 5) installation and the log I attached is from a client. The problem is appearing on all clients (inc the one hosting TVserver). HDD on both the client and the server are relatively quiet - only one user and not much background activity.

If the problem is occurring on all clients then it would be helpful if you can post the TV Server logs.

1) Is there round trip to the TVserver for each new EPG row being rendered for the first time?

Sorry, I don't know.

2) If not - where does the client store the info - in the Video db or memory?

Memory. The video DB is not used by TV at all - it is entirely dedicated to video.

3) Is there a way to see if there are any indexes missing from DBs (MySQL or client side)?

You'd be looking at MySQL only on the server side. One thing you could do is check the MySQL slow query log.

mm

 

[JackTramiel]

AW: Very slow EPG scrolling

@mm : So the epg data will be copied from server to client memory (RAM) ?

 

[ianc]

mm
I checked the TVServer logs before and after doing the clnt side EPG scroll - Interestngly no log activity at all. I can't see a way of adjusting the detail level for the TVServer log

I then checked MySQL via mysql->status and got this

mysql> status
--------------
mysql  Ver 14.14 Distrib 5.1.38, for Win32 (ia32)

Connection id:          91
SSL:                    Not in use
Using delimiter:        ;
Server version:         5.1.38-community MySQL Community Server (GPL)
Protocol version:       10
Connection:             localhost via TCP/IP
Client characterset:    utf8
Server characterset:    utf8
TCP port:               3306
Uptime:                 4 days 6 hours 50 min 25 sec

Threads: 6  Questions: 1177547  Slow queries: 8  Opens: 908  Flush tables: 1  Op
en tables: 17  Queries per second avg: 3.180
--------------

mysql>

So it does seem to be showing slow queries. I downloaded MySQL Workbench to see if it could tell me which one were slow. I couldn't get it to do so. I did turn on slow-query-log and specified a log file - but nothing in it...

Thoughts?