various issues today (1 Viewer)

mrkaras

Portal Pro
December 9, 2006
541
7
Home Country
this morning I turned on my TV and this is what I got:

tried to start live TV (from the EPG page) and MP locked up, so I killed it and restarted the client
all sorts of crazy flickering started going on, thinking back perhaps I managed to get 2 copies of the client to run at once so I tried to restart the PC from within MP. the flickering stopped but the PC did not restart so I went to the TV guide and sat that it had forgotten the channel group, I had it on "standard" but it had forgotten that and changed to all channels.
I changed to the "standard" channel group and tried to start a program and got a message saying "program/channel not found".
restarted the PC (from within MP, worked fine this time).
went to the TV guide and found the group had been forgotten again (was an all channels)

attempted to collect the logs but messed up and used report a bug to the MP team, hope it didn't delete everything needed? exited MP.
run the watchdog again with the using the collect existing logs and posted here

Apart from the flickering and failed shutdown this stuff I've seen before, but after startup I didn't expect the server to be running as it usually isn't (because MP doesn't wait for the database to be ready, only waits for SQL server service to be ready not necessarily the TV server database to be up). perhaps reinstalling the latest version recently has helped this.

I were actually hoping to capture the volume issue, where after resume the volume control doesn't work, but it seemed fine on the menus, perhaps there may be cases further back in the logs but I can't say exactly where (but would be immediately after probably about half the resumes in the morning)
 

mm1352000

Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    Hello

    tried to start live TV (from the EPG page) and MP locked up, so I killed it and restarted the client
    MP was waiting for TV Server to tune.
    TV Server was having problems interacting with your tuners.
    It looks like this all started at the point the PC went to sleep last night. TV Server was in the middle of tuning for EPG grabbing the PowerScheduler plugin instructed the PC to go to sleep (standby):
    [collapse]
    [2015-06-15 02:19:18,354] [Log ] [DVB EPG timer] [INFO ] - dvbt: Tune:DVBT:tv:Ten Brisbane TEN Digital Freq:219500 ONID:4116 TSID:1540 SID:1605 PMT:0x100 FTA:True LCN:10000 BandWidth:7
    [2015-06-15 02:19:18,355] [Log ] [DVB EPG timer] [INFO ] - dvb:confused:ubmiting tunerequest Channel:TEN Digital subChannel:-1
    [2015-06-15 02:19:18,355] [Log ] [DVB EPG timer] [INFO ] - dvb:Getting new subchannel
    [2015-06-15 02:19:18,355] [Log ] [DVB EPG timer] [INFO ] - dvb:GetNewSubChannel:0 #0
    [2015-06-15 02:19:18,356] [Log ] [DVB EPG timer] [INFO ] - TvDvbChannel ctor new subchIndex:0
    [2015-06-15 02:19:18,356] [Log ] [DVB EPG timer] [INFO ] - card: AddTuneEvent card: 8 / subch: 0
    [2015-06-15 02:19:18,356] [Log ] [DVB EPG timer] [INFO ] - dvb:confused:ubmit tunerequest size:1 new:0
    [2015-06-15 02:19:18,357] [Log ] [DVB EPG timer] [INFO ] - subch:0 OnBeforeTune
    [2015-06-15 02:19:18,357] [Log ] [DVB EPG timer] [INFO ] - dvb:confused:ubmit tunerequest calling put_TuneRequest
    [2015-06-15 02:19:18,394] [Log ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
    [2015-06-15 02:19:18,402] [Log ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
    [2015-06-15 02:19:18,416] [Log ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
    [2015-06-15 02:19:18,803] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Scheduled Recordings: 15/06/2015 8:42:00 AM
    [2015-06-15 02:19:18,819] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 15/06/2015 8:41:00 AM
    [2015-06-15 02:19:18,820] [Log ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
    [2015-06-15 02:19:18,824] [Log ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyAllowed)
    [2015-06-15 02:19:18,824] [Log ] [PS StandbyWakeup] [DEBUG] - PS: System is idle and may go to standby
    [2015-06-15 02:19:18,824] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Active standby is enabled - go to standby now
    [2015-06-15 02:19:18,825] [Log ] [PS StandbyWakeup] [INFO ] - PS: System suspend requested by PowerScheduler
    [2015-06-15 02:19:18,838] [Log ] [PS StandbyWakeup] [DEBUG] - PS: SuspendSystem(source: , how: Suspend, force: False)
    [2015-06-15 02:19:18,839] [Log ] [PS StandbyWakeup] [DEBUG] - PS: Kick off shutdown thread (how: Suspend)
    [2015-06-15 02:19:18,840] [Log ] [PS Suspend] [DEBUG] - PS: Shutdown thread is running: how: Suspend, force: False
    [2015-06-15 02:19:18,840] [Log ] [PS Suspend] [DEBUG] - PS: Informing handlers about UserShutdownNow
    [2015-06-15 02:19:18,842] [Log ] [PS Suspend] [INFO ] - PS: Entering shutdown: how: Suspend, force: False
    [2015-06-15 02:19:18,843] [Log ] [WinController exit thread] [DEBUG] - WindowsController: Performing ExitWindows Suspend, force: False
    [2015-06-15 02:19:18,909] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread received WM_POWERBROADCAST 4
    [2015-06-15 02:19:18,910] [Log ] [PowerEventThread] [DEBUG] - OnPowerEvent: PowerStatus: Suspend
    [2015-06-15 02:19:18,910] [Log ] [PowerEventThread] [DEBUG] - OnPowerEventHandler: PowerStatus: Suspend
    [2015-06-15 02:19:18,910] [Log ] [PowerEventThread] [INFO ] - TvController.OnSuspend()
    [2015-06-15 02:19:18,921] [Log ] [PowerEventThread] [INFO ] - Scheduler: stopped
    [2015-06-15 02:19:19,100] [Log ] [PowerEventThread] [DEBUG] - Scheduler: thread stopped.
    [2015-06-15 02:19:19,129] [Log ] [PowerEventThread] [INFO ] - Stopcard
    [2015-06-15 02:19:19,130] [Log ] [PowerEventThread] [INFO ] - Stopcard
    [2015-06-15 02:19:19,130] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
    [2015-06-15 02:19:19,130] [Log ] [PowerEventThread] [INFO ] - Stopcard
    [2015-06-15 02:19:19,147] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
    [2015-06-15 02:19:19,147] [Log ] [PowerEventThread] [INFO ] - Stopcard
    [2015-06-15 02:19:19,147] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
    [2015-06-15 02:19:19,148] [Log ] [PowerEventThread] [INFO ] - DVB subch:0 Decompose()
    [2015-06-15 02:19:19,148] [Log ] [PowerEventThread] [INFO ] - FreeSubChannel CA: freeing sub channel : 0
    [2015-06-15 02:19:19,148] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph
    [2015-06-15 02:19:19,149] [Log ] [PowerEventThread] [INFO ] - dvb:pauseGraph called
    [2015-06-15 02:19:19,149] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
    [2015-06-15 02:19:19,149] [Log ] [PowerEventThread] [INFO ] - dvb:pauseGraph filterstate already paused, returning.
    [2015-06-15 02:19:19,149] [Log ] [PowerEventThread] [INFO ] - dvb:confused:topGraph called
    [2015-06-15 02:19:19,149] [Log ] [PowerEventThread] [INFO ] - tvcard:FreeAllSubChannels
    [2015-06-15 02:19:19,150] [Log ] [PowerEventThread] [INFO ] - dvb:confused:topGraph
    [/collapse]

    After this both TV Server and the tuner were deadlocked and/or borked.

    I don't know why PS thought it was okay to put the computer to sleep while the tuner was tuning, but that is always going to be problematic. I'd advise you to check the PS plugin settings. Specifically, check the setting on the EPG tab in expert mode: "do not put the computer to sleep while grabbing EPG".

    all sorts of crazy flickering started going on...
    Sorry, I'm not able to visualise what you're describing, and the MediaPortal log files don't cover this time.

    but the PC did not restart
    I suspect this is because TV Server prevented it. Like I said above, TV Server was borked by what happened previously. When I say borked, I really mean borked.

    so I went to the TV guide and sat that it had forgotten the channel group, I had it on "standard" but it had forgotten that and changed to all channels.
    As above: the log files don't cover this. All I can say is that:
    1. The group selection is saved when you stop TV and/or leave the TV section. If you didn't do one of those things then the group selection won't be saved.
    2. Your MediaPortal config file shows that your saved group is currently "standard".

    I changed to the "standard" channel group and tried to start a program and got a message saying "program/channel not found".
    I can see this in the TV Server log.
    Looks like the tuner wasn't delivering a stream. I've looked back through your older logs to check if that tuner is normally able to tune that channel, but I can't see any previous attempts. TV Server seems to always use a different tuner for that channel.
    In short: I don't know whether this is a one off (because TV Server was borked, as above) or a general issue with that tuner being unable to tune that channel.

    went to the TV guide and found the group had been forgotten again (was an all channels)
    As above.

    attempted to collect the logs but messed up and used report a bug to the MP team, hope it didn't delete everything needed? exited MP.
    As above, I only have TV Server logs. Without the MP logs I can't check into some of the things you've described.

    Apart from the flickering and failed shutdown this stuff I've seen before, but after startup I didn't expect the server to be running as it usually isn't (because MP doesn't wait for the database to be ready, only waits for SQL server service to be ready not necessarily the TV server database to be up). perhaps reinstalling the latest version recently has helped this.
    Sorry, I don't understand any of this.

    I were actually hoping to capture the volume issue...
    Sorry, I don't have the MP logs due to your "report a bug" mistake.
     

    mrkaras

    Portal Pro
    December 9, 2006
    541
    7
    Home Country
    Thanks, glad something useful was still there. I have changed the setting described as it was not checked and I would have turned off the PC with the power button on the remote, it goes to away mode if recording but I guess breaks stuff if just doing an EPG update.

    I also found an option to reinitialize TV controller on wake up, hopefully that works around the volume issue anyway.

    not overly concerned about the other things you couldn't help with and perhaps the service not starting issue is gone now but just to clarify out of anyone's interest what I were trying to say in the bit you didn't understand:
    in a previous thread I mentioned the TV server didn't run at startup, looking into it, it was found that it was because it could not access the database. This was because although the TV service will wait for SQL server to be running that is not enough to guarantee that SQL server has all its databases online yet, and in some cases SQL server its self is running but the TV server database is not yet online for presumable a short period of time. this was causing the TV service to fail at startup, every time. this SQL server behaviors sounds strange but is mentioned elsewhere and I have run into the same issue myself in some non MP related software I have developed previously. Anyway, perhaps I'm no longer having this issue, we'll see.
     

    mm1352000

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    I have changed the setting described as it was not checked and I would have turned off the PC with the power button on the remote, it goes to away mode if recording but I guess breaks stuff if just doing an EPG update.
    To be clear, I was just asking you to check what the value of the setting was... not necessarily to enable it.
    Also, in this particular case the PC went to sleep shortly after a recording completed at approximately 2:20 AM. I don't know if the PC was already in away mode, but I wouldn't have thought that would have made any difference.

    I also found an option to reinitialize TV controller on wake up...
    ...which you should not enable unless you know what you're doing.

    ...hopefully that works around the volume issue anyway.
    I have no idea what this "volume issue" is that you're talking about. However, I can guarantee that enabling that option won't make any difference to it... unless the issue is caused by failure to receive remote control commands, and your remote control receiver is connected to one of your tuners.

    In other words...
    That setting is a TV Server setting. MediaPortal's volume is completely separate and unrelated to TV Server. Therefore I wouldn't expect changes to the setting to make any difference.

    not overly concerned about the other things you couldn't help with
    Okay.
     

    Lehmden

    Retired Team Member
  • Premium Supporter
  • December 17, 2010
    12,553
    3,934
    Lehmden
    Home Country
    Germany Germany
    Hi.
    Sorry, I don't understand any of this.
    The days I was using MP1 TV server I also had this issue real often. At boot time the TV server did not start as it lasts a while between mysqld actually is running and the MP database being accessible. This depends on many things and is not 100% reproducible. But in most cases the TV server did not start properly here after a reboot as it only waits for mysqld to be running, not the DB being accessible. And TV Service is too fast on giving up so MySQL is not ready to grant DB access before TV service quits. As a workaround I didn't start the service automatically but with a batch in startup folder of the start menu:
    Code:
    net start mysqld
    wait 5
    net start tvservice
    First start MySQL. Then wait 5 seconds before starting TV Service...
    This was the only way to get the TV service starting reliable on any of my systems. This issue also was there with SQLExpress but not as heavy as with MySQL.

    Now that I'm using MP2 this is no issue any more as MP2 did not need an external database at all... And for this I can not tell if this issue still is there when using TVE 3.5 as I only tested TVE 3.5 together with MP2...
     

    Users who are viewing this thread

    Top Bottom