MediaPortal Wait many times on Wait Tvserver (1 Viewer)

[azzuro]

MediaPortal Version: 1.4.0

Description
When Start MP on the first time in a day.

MP need wait many time on "Waiting TVserver" on splachscreen view.

and when restart HTPC on this day, no more waiting !

So, i think it's XMLTV issue.

because i have set than XMLTV grabbing EPG 1 time/day and my HTPC is slow and internet connection is bad.
need lot of time for download EPG and insert it in MySQL.

MP should wait TVservice processus started before starting MP,

but i think xmltv is added in initialized processus = really bad .


i think the better things is to start tvservice and plugins initilialization only, but plugins should wait tvservice initialized, before plugins start work.
@mm1352000 @mattjcury

Steps to Reproduce:
system : Singleseat
Start MP as Shell
with xmltv support.

better is to test on low power system

 

[Sebastiii]

It seems that xmltv is not started in separate thread from your input.
But i'm not sure.

Nothing in log can tell is tvservice is busy ?

 

[azzuro]

logs are uploaded ( only returned to home) on 3/4 days
my htpc is not used every days ...

 

[azzuro]

@mm1352000
you can help with this ?

 

[mm1352000]

@mm1352000
you can help with this ?

I don't think this problem has anything to do with XMLTV... and you can prove this with a simple test: disable the XMLTV plugin and see how long it takes for MP to start.

You can see this in the MP log files:

[2013-08-08 18:19:19,906] [Log ] [MPMain ] [INFO ] - Main: TV service is stopped, so we try start it...
[2013-08-08 18:19:48,410] [Log ] [MPMain ] [INFO ] - TvService seems to be already starting up.
[2013-08-08 18:19:48,410] [Log ] [MPMain ] [INFO ] - Main: The TV service has started successfully.

...and in the TV service log:

[2013-08-08 18:19:32,496] [Log ] [4 ] [DEBUG] - Setting up EventWaitHandle with name: Global\MPTVServiceInitializedEvent
...
[2013-08-08 18:19:47,625] [Log ] [TVService] [INFO ] - TV Service: Load plugins
[2013-08-08 18:19:47,843] [Log ] [TVService] [INFO ] - PluginManager: Loaded ComSkipLauncher version:1.0.2.0 author:and-81
[2013-08-08 18:19:47,878] [Log ] [TVService] [INFO ] - PluginManager: Loaded ConflictsManager version:1.0.0.1 author:Broceliande
[2013-08-08 18:19:47,929] [Log ] [TVService] [INFO ] - PluginManager: Loaded Power Scheduler version:0.1.0.0 author:micheloe
[2013-08-08 18:19:47,988] [Log ] [TVService] [INFO ] - PluginManager: Loaded ServerBlaster version:1.1.1.0 author:joboehl with ralphy mods
[2013-08-08 18:19:48,019] [Log ] [TVService] [INFO ] - PluginManager: Loaded TV Movie EPG import version:1.0.3.0 author:rtv
[2013-08-08 18:19:48,074] [Log ] [TVService] [INFO ] - PluginManager: Loaded WebEPG version:1.0.0.0 author:Arion_p - James
[2013-08-08 18:19:48,113] [Log ] [TVService] [INFO ] - PluginManager: Loaded XmlTv version:1.0.0.0 author:Frodo
[2013-08-08 18:19:48,113] [Log ] [TVService] [INFO ] - TV Service: Plugins loaded
[2013-08-08 18:19:48,117] [Log ] [TVService] [INFO ] - TV Service: Plugin: ComSkipLauncher disabled
[2013-08-08 18:19:48,117] [Log ] [TVService] [INFO ] - TV Service: Plugin: ConflictsManager disabled
[2013-08-08 18:19:48,121] [Log ] [TVService] [INFO ] - TV Service: Plugin: Power Scheduler disabled
[2013-08-08 18:19:48,125] [Log ] [TVService] [INFO ] - TV Service: Plugin: ServerBlaster disabled
[2013-08-08 18:19:48,128] [Log ] [TVService] [INFO ] - TV Service: Plugin: TV Movie EPG import disabled
[2013-08-08 18:19:48,136] [Log ] [TVService] [INFO ] - TV Service: Plugin: WebEPG disabled
[2013-08-08 18:19:48,136] [Log ] [TVService] [INFO ] - TV Service: Plugin: XmlTv started
[2013-08-08 18:19:48,140] [Log ] [TVService] [INFO ] - plugin: xmltv started
[2013-08-08 18:19:48,140] [Log ] [TVService] [DEBUG] - xmltv: Registered xmltv as PowerEventHandler to tvservice
[2013-08-08 18:19:48,214] [Log ] [TVService] [INFO ] - TV Service: Plugins started
[2013-08-08 18:19:48,214] [Log ] [TVService] [INFO ] - TV Service: Plugin: XmlTv started all
[2013-08-08 18:19:48,214] [Log ] [TVService] [DEBUG] - XmlTvImporter: NOT registered with PowerScheduler EPG handler
[2013-08-08 18:19:48,214] [Log ] [TVService] [INFO ] - TV service: Started

So, XMLTV is started right at the end of the service load process. There is almost zero delay between XMLTV start and MP detecting that the service has finished starting.
I think that the problem is that your PC hardware is just too slow.

Processor: AMD Sempron(tm) Processor 2800+, ~1.6GHz
Memory: 1024MB RAM
Available OS Memory: 1024MB RAM
Page File: 1476MB used, 688MB available

The CPU speed is very slow, and it is a Sempron (AMD equivalent of Intel Celeron/Pentium) - it is not good.
Further, only 1GB of memory. This is not enough for W7. The sloooow swap file on your HDD will be used all the time.

So, I recommend you downgrade to XP on this PC... or get a new one.

 

[mm1352000]

Wikipedia is telling me your CPU is from 2004! I think time for an upgrade if you want startup to be faster.

 

[azzuro]

The CPU speed is very slow, and it is a Sempron (AMD equivalent of Intel Celeron/Pentium) - it is not good. Further, only 1GB of memory. This is not enough for W7. The sloooow swap file on your HDD will be used all the time. So, I recommend you downgrade to XP on this PC... or get a new one.

YOUR ARE CRAZYYYYYYYYYYYYYYYYY .
No more XP

LOL

So, XMLTV is started right at the end of the service load process. There is almost zero delay between XMLTV start and MP detecting that the service has finished starting. I think that the problem is that your PC hardware is just too slow.

i think this issue is not identified in LOG, because i need always do an Hard-Reset , so we should view in log 2 X MP starting on the same day also TVserveur.
but i think LOG is deleted when MP is restarted in the same day !
if this is true it's an BIG issue in LOG management.

i can't confirm it for now ( @work) . but if it's true. we need fix this ...

 

[azzuro]

Wikipedia is telling me your CPU is from 2004! I think time for an upgrade if you want startup to be faster.

it's planned, but first is to finish my House.
but for test it's better, if MP working great on old system > working better on fast system ((the false argument... lol )

 

[azzuro]

@Testers
Can you verify if in LOG, many MP startup ( not resume) on the same day are saved in LOG. in the same file or is renamed as Mediaportal-1.log for the same day.

Step : ( Hours are example)
- Start MP at 18h00 and close it, restart it at 18h20 close it.
-- verify LOG if you look MP starting at 18h00 and 18h20

- Start MP at 18h00 and KILL (with task management for simulate crash) it, restart it at 18h20 ( close or kill it).
-- verify LOG if you look MP starting at 18h00 and 18h20

Same things with TVservice. but only Killed version.

 

[mm1352000]

Can you verify if in LOG, many MP startup ( not resume) on the same day are saved in LOG. in the same file or is renamed as Mediaportal-1.log for the same day.

Logs are renamed as MediaPortal.1.log and TVService.1.log etc.

it's planned, but first is to finish my House.

Fair enough.

but for test it's better, if MP working great on old system > working better on fast system ((the false argument... lol )

YOUR ARE CRAZYYYYYYYYYYYYYYYYY .

No more XP

It was a real suggestion. Honestly, XP is not so bad as you seem to think it is - it is not the devil!
You can laugh and say that I'm crazy, but I still say XP would suit the age of the hardware better than W7. It can run better on less memory with lower power CPUs like you have. W7 vs. XP is your choice.

Here is the last analysis I will do for this problem...

i think this issue is not identified in LOG, because i need always do an Hard-Reset , so we should view in log 2 X MP starting on the same day also TVserveur.

Okay, let us look. I have compared the TV service and MP log files...


On 8 August you have the delay because TV service was not started when MP was starting. TV service took about 28 seconds to start.

[2013-08-08 18:19:19,906] [Log ] [MPMain ] [INFO ] - Main: TV service is stopped, so we try start it...
[2013-08-08 18:19:32,496] [Log ] [4 ] [DEBUG] - Setting up EventWaitHandle with name: Global\MPTVServiceInitializedEvent
[2013-08-08 18:19:48,214] [Log ] [TVService] [INFO ] - TV service: Started
[2013-08-08 18:19:48,410] [Log ] [MPMain ] [INFO ] - TvService seems to be already starting up.
[2013-08-08 18:19:48,410] [Log ] [MPMain ] [INFO ] - Main: The TV service has started successfully.
[2013-08-08 18:19:48,410] [Log ] [MPMain ] [INFO ] - Main: TV service is in status Running - proceeding...

On 7 August you have no delay because TV service had already started when MP started. TV service still took about 11 seconds to start.

[2013-08-07 19:30:10,062] [Log ] [4 ] [DEBUG] - Setting up EventWaitHandle with name: Global\MPTVServiceInitializedEvent
[2013-08-07 19:30:21,152] [Log ] [TVService] [INFO ] - TV service: Started
[2013-08-07 19:30:21,308] [Log ] [MPMain ] [INFO ] - TvService seems to be already starting up.
[2013-08-07 19:30:21,308] [Log ] [MPMain ] [INFO ] - Main: The TV service has started successfully.
[2013-08-07 19:30:21,308] [Log ] [MPMain ] [INFO ] - Main: TV service is in status Running - proceeding...

On 4 August you have the delay because TV service was not started when MP was starting. TV service took about 13 seconds to start.

[2013-08-04 21:59:13,890] [Log ] [MPMain ] [INFO ] - Main: TV service is stopped, so we try start it...
[2013-08-04 21:59:30,460] [Log ] [4 ] [DEBUG] - Setting up EventWaitHandle with name: Global\MPTVServiceInitializedEvent
[2013-08-04 21:59:43,746] [Log ] [TVService] [INFO ] - TV service: Started
[2013-08-04 21:59:44,035] [Log ] [MPMain ] [INFO ] - TvService seems to be already starting up.
[2013-08-04 21:59:44,035] [Log ] [MPMain ] [INFO ] - Main: The TV service has started successfully.
[2013-08-04 21:59:44,035] [Log ] [MPMain ] [INFO ] - Main: TV service is in status Running - proceeding...

When you try to start MP and the TV service at the same time (probably Windows is also still starting) then RAM runs out and Windows must use your HDD as swap memory... which is really slow. So, allow the TV service to start and W7 to load before you try to start MP.