[solved] major issues with DVB-T channel change after upgrading to MP 1.34 (3 Viewers)

[Hazza06]

MP1 Version

1.33

Long time MP user, and recently upgraded to MP1.34, prior to this upgrade, i have for many years successfully used MP with fast and reliable DVB-T FTA channel change.

Last stable MP release for me was 1.31, never had any channel change issues, was always fast and reliable.

I also needed to upgrade to the latest stable version of 'MediaPortal IPTV Source Splitter' to solve this issue -> [solved] - upgrade to MP 1.33, now getting MP TV server configuration dialog box error

I have the Hauppauge HVR-2200 DVB-T card, which is unchanged in it's BDA driver for many years, dated 2012 ( which is the latest /last Microsoft Windows Hardware Compatibility Published version for several years now ) for Win 7 x64

I work in L3/L4 IT / network engineering for more than 30 years now, know my way around PC's and servers, and networking, and OCD about change control, with devices that the family use....there's no other change to the system for many years now, apart from MP upgrades.

After successfully resuming from ACPI S3 sleep state, Often channel changes are extremely slow now after the upgrade to MP 1.34, and also often channel changes fail altogether...

Restarting the TV service improves the issue, until next sleep/resume to/from ACPI S3 sleep state.

I've been using S3 sleep state with rock solid performance without any MP channel change issues for more than 10 years now...

TVServer timeshift F: drive is a RAM disk, and remains unchanged in several years

MP client and TV server run on the same host....as you can observe localhost referenced in TVService.log below....

I have already deleted all DVB-T channels, and re-scanned all channels, as expected, all channels found, but did not resolve the issue...

Also where we live, we have direct line of sight to the Mt. Dandenong TV tower transmission site, and we have perfect reception....even in the worst of worst filthy inclement Melbourne weather...

example below of failed channel change

I note the below exception whilst channel change is seemingly in progress 'Exception in Program.Persist() with Message insert into Program'

I also note some spurious looking events from PS StandbyWakeup, by the looks of it mentioning SetStandbyMode(AwayModeRequested), but i did not press the power button on remote that would have triggered awaymode....

from MediaPortal.log

[2024-08-01 20:23:43,663] [Log    ] [MPMain   ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=9HD Melbourne
[2024-08-01 20:23:45,195] [Log    ] [MPMain   ] [INFO ] - TSReaderPlayerause graph
[2024-08-01 20:23:55,709] [Log    ] [MPMain   ] [ERROR] - TvServer: Failed to execute remote TV server operation (StartTimeShifting.2) A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond

from tvserver_TVService.log

[2024-08-01 20:23:43,683] [Log    ] [12       ] [INFO ] - Controller: TimeShiftingWouldUseCard 9HD Melbourne 162
[2024-08-01 20:23:43,842] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for Double J from 8/4/2024 9:45:00 PM to 8/4/2024 11:59:00 PM
[2024-08-01 20:23:43,993] [Log    ] [12       ] [DEBUG] - GetFreeCardsForChannel 9HD Melbourne
[2024-08-01 20:23:44,141] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry A mix of your favourite music, new tunes and the stories behind the music. (8/4/2024 11:59:00 PM - 8/4/2024 11:59:00 PM)
[2024-08-01 20:23:44,287] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel: find card for channel 9HD Melbourne
[2024-08-01 20:23:44,447] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for Double J from 8/5/2024 9:00:00 PM to 8/5/2024 11:59:00 PM
[2024-08-01 20:23:44,602] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   got 1 tuning details for 9HD Melbourne
[2024-08-01 20:23:44,750] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry A mix of your favourite music, new tunes and the stories behind the music. (8/5/2024 11:59:00 PM - 8/5/2024 11:59:00 PM)
[2024-08-01 20:23:44,902] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   channel #1 DVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:44,914] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:1 type:RadioWebStream is disabled
[2024-08-01 20:23:44,927] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:2 typevbIP is disabled
[2024-08-01 20:23:44,937] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for Double J from 8/6/2024 9:00:00 PM to 8/6/2024 11:59:00 PM
[2024-08-01 20:23:44,947] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:3 type:Analog is disabled
[2024-08-01 20:23:44,959] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry A mix of your favourite music, new tunes and the stories behind the music. (8/6/2024 11:59:00 PM - 8/6/2024 11:59:00 PM)
[2024-08-01 20:23:44,969] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:4 type:Analog is disabled
[2024-08-01 20:23:44,982] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   card:5 typevbT can tune to channel
[2024-08-01 20:23:44,994] [Log    ] [12       ] [INFO ] - NumberOfOtherUsersOnCurrentCard:    card:5 typevbT users: 0
[2024-08-01 20:23:45,005] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for Double J from 8/7/2024 9:00:00 PM to 8/7/2024 11:59:00 PM
[2024-08-01 20:23:45,015] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   card:6 typevbT can tune to channel
[2024-08-01 20:23:45,025] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry A mix of your favourite music, new tunes and the stories behind the music. (8/7/2024 11:59:00 PM - 8/7/2024 11:59:00 PM)
[2024-08-01 20:23:45,036] [Log    ] [12       ] [INFO ] - NumberOfOtherUsersOnCurrentCard:    card:6 typevbT users: 0
[2024-08-01 20:23:45,048] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel: found 2 card(s) for channel
[2024-08-01 20:23:45,062] [Log    ] [12       ] [DEBUG] - GetAvailableCardsForChannel took 921 msec
[2024-08-01 20:23:45,072] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for Double J from 8/8/2024 9:00:00 PM to 8/8/2024 11:59:00 PM
[2024-08-01 20:23:45,082] [Log    ] [12       ] [INFO ] - GetFreeCardsForChannel found 2 free card(s), channel: 9HD Melbourne, user:htpc-i3
[2024-08-01 20:23:45,093] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry A mix of your favourite music, new tunes and the stories behind the music. (8/8/2024 11:59:00 PM - 8/8/2024 11:59:00 PM)
[2024-08-01 20:23:45,104] [Log    ] [12       ] [DEBUG] - GetFreeCardsForChannel, free card:0, id:6, STCA:True, ST:True, PRI:6, CL:0, NOU:0
[2024-08-01 20:23:45,117] [Log    ] [12       ] [DEBUG] - GetFreeCardsForChannel, free card:1, id:5, STCA:False, ST:False, PRI:5, CL:1, NOU:0
[2024-08-01 20:23:45,127] [EPG    ] [EPG Update thread] [INFO ] - - Inserted 82 epg entries for channel Double J
[2024-08-01 20:23:45,137] [Log    ] [12       ] [DEBUG] - GetFreeCardsForChannel took 1285 msec
[2024-08-01 20:23:45,147] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Jazz lastUpdate:8/1/2024 7:38:14 PM
[2024-08-01 20:23:45,158] [Log    ] [12       ] [DEBUG] - CiMenuSupported called cardid 6
[2024-08-01 20:23:45,170] [Log    ] [12       ] [DEBUG] - CiMenuSupported card Hauppauge WinTV-7164 BDA DVBT Tuner supported: False
[2024-08-01 20:23:45,211] [Log    ] [12       ] [INFO ] - Controller: StartTimeShifting 9HD Melbourne 162
[2024-08-01 20:23:45,223] [Log    ] [12       ] [INFO ] - Controller: epg stop, caller 5
[2024-08-01 20:23:45,233] [Log    ] [12       ] [DEBUG] - GetFreeCardsForChannel 9HD Melbourne
[2024-08-01 20:23:45,246] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel: find card for channel 9HD Melbourne
[2024-08-01 20:23:45,259] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   got 1 tuning details for 9HD Melbourne
[2024-08-01 20:23:45,269] [EPG    ] [EPG Update thread] [INFO ] - - Inserted 46 epg entries for channel ABC Jazz
[2024-08-01 20:23:45,279] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   channel #1 DVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:45,290] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC KIDS Listen lastUpdate:8/1/2024 7:38:14 PM
[2024-08-01 20:23:45,299] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:1 type:RadioWebStream is disabled
[2024-08-01 20:23:45,311] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:2 typevbIP is disabled
[2024-08-01 20:23:45,321] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:3 type:Analog is disabled
[2024-08-01 20:23:45,333] [Log    ] [12       ] [INFO ] - CanCardTuneChannel:            card:4 type:Analog is disabled
[2024-08-01 20:23:45,345] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   card:5 typevbT can tune to channel
[2024-08-01 20:23:45,358] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel:   card:6 typevbT can tune to channel
[2024-08-01 20:23:45,370] [Log    ] [12       ] [INFO ] - GetAvailableCardsForChannel: found 2 card(s) for channel
[2024-08-01 20:23:45,381] [Log    ] [12       ] [DEBUG] - GetAvailableCardsForChannel took 135 msec
[2024-08-01 20:23:45,392] [Log    ] [12       ] [INFO ] - GetFreeCardsForChannel found 2 available card(s), channel: 9HD Melbourne, user:htpc-i3
[2024-08-01 20:23:45,404] [Log    ] [12       ] [DEBUG] - GetFreeCardsForChannel took 170 msec
[2024-08-01 20:23:45,421] [Log    ] [12       ] [DEBUG] - RequestCardTuneReservation: placed reservation with id=117, tuningdetails=DVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:45,435] [Log    ] [12       ] [DEBUG] - RequestCardTuneReservation: placed reservation with id=118, tuningdetails=DVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:45,447] [Log    ] [12       ] [DEBUG] - UpdateFreeCardsForChannelBasedOnTicket: user: htpc-i3
[2024-08-01 20:23:45,458] [Log    ] [12       ] [INFO ] - UpdateFreeCardsForChannelBasedOnTicket found 2 free card(s), user:htpc-i3
[2024-08-01 20:23:45,471] [Log    ] [12       ] [DEBUG] - UpdateFreeCardsForChannelBasedOnTicket, free card:0, id:6, STCA:True, ST:True, PRI:6, CL:0, NOU:1
[2024-08-01 20:23:45,482] [Log    ] [12       ] [DEBUG] - UpdateFreeCardsForChannelBasedOnTicket, free card:1, id:5, STCA:False, ST:False, PRI:5, CL:1, NOU:0
[2024-08-01 20:23:45,494] [Log    ] [12       ] [INFO ] - Controller: try max 2 of 2 cards for timeshifting
[2024-08-01 20:23:45,506] [Log    ] [12       ] [DEBUG] - CardReservationBase.CardTune: tvcard=6, user=htpc-i3, dbChannel=162, ticket=117, tunestate=Tuning, stopstate=Idle, ticketFTA=True
[2024-08-01 20:23:45,518] [Log    ] [12       ] [INFO ] - CardTuner.CardTune: 6 9HD Melbourne htpc-i3:6:-1
[2024-08-01 20:23:45,530] [Log    ] [12       ] [INFO ] - CardTuner.Tune: Tune on card 6 to subchannel 9HD Melbourne
[2024-08-01 20:23:45,542] [Log    ] [12       ] [DEBUG] - CardTuner.BeforeTune: user: htpc-i3:6:-1 tune DVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:45,552] [EPG    ] [EPG Update thread] [INFO ] - - Inserted 114 epg entries for channel ABC KIDS Listen
[2024-08-01 20:23:45,564] [Log    ] [12       ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#-1
[2024-08-01 20:23:45,575] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: triple j lastUpdate:8/1/2024 7:38:15 PM
[2024-08-01 20:23:45,584] [Log    ] [12       ] [INFO ] - tvcard:FreeSubChannel :-1 - sub channel not found
[2024-08-01 20:23:45,594] [Log    ] [12       ] [INFO ] - tvcard:FreeSubChannel : subchannels STILL present 1, continuing graph
[2024-08-01 20:23:45,607] [Log    ] [12       ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events
[2024-08-01 20:23:45,619] [Log    ] [12       ] [INFO ] - dvbt: TuneVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:45,629] [Log    ] [12       ] [INFO ] - dvbubmiting tunerequest Channel:9HD Melbourne subChannel:-1
[2024-08-01 20:23:45,642] [Log    ] [12       ] [INFO ] - dvb:Getting new subchannel
[2024-08-01 20:23:45,656] [Log    ] [12       ] [INFO ] - dvb:GetNewSubChannel:1 #5
[2024-08-01 20:23:45,669] [Log    ] [12       ] [INFO ] - TvDvbChannel ctor new subchIndex:5
[2024-08-01 20:23:45,682] [Log    ] [12       ] [INFO ] - card: AddTuneEvent card: 6 / subch: 5
[2024-08-01 20:23:45,693] [Log    ] [12       ] [INFO ] - dvbubmit tunerequest size:2 new:5
[2024-08-01 20:23:45,706] [Log    ] [12       ] [INFO ] - subch:5 OnBeforeTune
[2024-08-01 20:23:45,718] [Log    ] [12       ] [INFO ] - subch:5 OnAfterTune
[2024-08-01 20:23:45,730] [Log    ] [12       ] [INFO ] - RunGraph
[2024-08-01 20:23:45,750] [EPG    ] [EPG Update thread] [INFO ] - - Inserted 75 epg entries for channel triple j
[2024-08-01 20:23:45,761] [Log    ] [12       ] [INFO ] - dvb:  LockInOnSignal ok
[2024-08-01 20:23:45,771] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: triple j Unearthed lastUpdate:8/1/2024 7:38:15 PM
[2024-08-01 20:23:45,780] [Log    ] [12       ] [INFO ] - subch:5 OnGraphStart
[2024-08-01 20:23:45,792] [Log    ] [12       ] [INFO ] - subch:5 Graph already running - WaitForPMT
[2024-08-01 20:23:45,804] [Log    ] [12       ] [INFO ] - subch:5 SetupPmtGrabberid 101 sid:431
[2024-08-01 20:23:45,816] [Log    ] [12       ] [INFO ] - subch:5 set pmt grabber pmt:101 sid:431
[2024-08-01 20:23:45,828] [Log    ] [12       ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time
[2024-08-01 20:23:45,838] [EPG    ] [EPG Update thread] [INFO ] - - Inserted 20 epg entries for channel triple j Unearthed
[2024-08-01 20:23:45,847] [Log    ] [12       ] [DEBUG] - WaitForPMT: Waiting for PMT 101
[2024-08-01 20:23:45,858] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Classic lastUpdate:8/1/2024 7:38:15 PM
[2024-08-01 20:23:45,961] [EPG    ] [EPG Update thread] [INFO ] - - Inserted 57 epg entries for channel ABC Classic
[2024-08-01 20:23:45,972] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Country lastUpdate:8/1/2024 7:38:20 PM
[2024-08-01 20:23:45,987] [Log    ] [EPG Update thread] [ERROR] - Exception in Program.Persist() with Message insert into Program ( idChannel, startTime, endTime, title, description, genre, state, originalAirDate, seriesNum, episodeNum, episodePart, episodeName, starRating, classification, parentalRating ) values ( ?idChannel, ?startTime, ?endTime, ?title, ?description, ?genre, ?state, ?originalAirDate, ?seriesNum, ?episodeNum, ?episodePart, ?episodeName, ?starRating, ?classification, ?parentalRating ); select LAST_INSERT_ID();
[2024-08-01 20:23:46,008] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 11:00:00 AM to 8/1/2024 11:59:00 AM
[2024-08-01 20:23:46,017] [Log    ] [22       ] [INFO ] - subch:5 OnPMTReceived() pmt:101 ran:True dynamic:False
[2024-08-01 20:23:46,027] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 11:59:00 AM - 8/1/2024 11:59:00 AM)
[2024-08-01 20:23:46,037] [Log    ] [12       ] [DEBUG] - WaitForPMT: Found PMT after 0.1900109 seconds.
[2024-08-01 20:23:46,048] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 12:00:00 PM to 8/1/2024 12:59:00 PM
[2024-08-01 20:23:46,058] [Log    ] [12       ] [INFO ] - subch:5 SendPmt:431 431 FFFFFFFF 0
[2024-08-01 20:23:46,068] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 12:59:00 PM - 8/1/2024 12:59:00 PM)
[2024-08-01 20:23:46,078] [Log    ] [12       ] [INFO ] - Decode pmt
[2024-08-01 20:23:46,089] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 1:00:00 PM to 8/1/2024 1:59:00 PM
[2024-08-01 20:23:46,100] [Log    ] [12       ] [INFO ] - Set descriptor data with length 6
[2024-08-01 20:23:46,111] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 1:59:00 PM - 8/1/2024 1:59:00 PM)
[2024-08-01 20:23:46,121] [Log    ] [12       ] [INFO ] - Set descriptor data with length 7
[2024-08-01 20:23:46,132] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 2:00:00 PM to 8/1/2024 3:59:00 PM
[2024-08-01 20:23:46,142] [Log    ] [12       ] [INFO ] - Set descriptor data with length 3
[2024-08-01 20:23:46,153] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 3:59:00 PM - 8/1/2024 3:59:00 PM)
[2024-08-01 20:23:46,163] [Log    ] [12       ] [INFO ] - subch:5 SendPMT: Channel is FTA, nothing to do.
[2024-08-01 20:23:46,175] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 4:00:00 PM to 8/1/2024 6:59:00 PM
[2024-08-01 20:23:46,184] [Log    ] [12       ] [INFO ] - subch:5 SetMpegPidMapping
[2024-08-01 20:23:46,194] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 6:59:00 PM - 8/1/2024 6:59:00 PM)
[2024-08-01 20:23:46,204] [Log    ] [12       ] [INFO ] - subch:5  pid:81 pcr
[2024-08-01 20:23:46,215] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 7:00:00 PM to 8/1/2024 8:59:00 PM
[2024-08-01 20:23:46,225] [Log    ] [12       ] [INFO ] - subch:5  pid:101 pmt
[2024-08-01 20:23:46,235] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 8:59:00 PM - 8/1/2024 8:59:00 PM)
[2024-08-01 20:23:46,245] [Log    ] [12       ] [INFO ] - subch:5  pid:201 video type:H.264
[2024-08-01 20:23:46,255] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 9:00:00 PM to 8/1/2024 9:59:00 PM
[2024-08-01 20:23:46,265] [Log    ] [12       ] [INFO ] - subch:5    map pid:201 video type:H.264
[2024-08-01 20:23:46,274] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 9:59:00 PM - 8/1/2024 9:59:00 PM)
[2024-08-01 20:23:46,284] [Log    ] [12       ] [INFO ] - subch:5  pid:28A audio lang: type:AC3
[2024-08-01 20:23:46,295] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/1/2024 10:00:00 PM to 8/1/2024 11:59:00 PM
[2024-08-01 20:23:46,305] [Log    ] [12       ] [INFO ] - subch:5    map pid:28A audio lang: type:AC3
[2024-08-01 20:23:46,314] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/1/2024 11:59:00 PM - 8/1/2024 11:59:00 PM)
[2024-08-01 20:23:46,324] [Log    ] [12       ] [INFO ] - subch:5  pid:241 teletext type:6
[2024-08-01 20:23:46,335] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 12:00:00 AM to 8/2/2024 2:59:00 AM
[2024-08-01 20:23:46,344] [Log    ] [12       ] [INFO ] - subch:5    map pid:241 teletext type:6
[2024-08-01 20:23:46,354] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 2:59:00 AM - 8/2/2024 2:59:00 AM)
[2024-08-01 20:23:46,364] [Log    ] [12       ] [INFO ] - subch:5  pid:321 type:5
[2024-08-01 20:23:46,374] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 3:00:00 AM to 8/2/2024 5:04:00 AM
[2024-08-01 20:23:46,384] [Log    ] [12       ] [INFO ] - subch:5  pid:323 type:C
[2024-08-01 20:23:46,393] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 5:04:00 AM - 8/2/2024 5:04:00 AM)
[2024-08-01 20:23:46,403] [Log    ] [12       ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed
[2024-08-01 20:23:46,414] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 5:05:00 AM to 8/2/2024 5:29:00 AM
[2024-08-01 20:23:46,423] [Log    ] [12       ] [INFO ] - subch:5 stop tif
[2024-08-01 20:23:46,439] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 5:29:00 AM - 8/2/2024 5:29:00 AM)
[2024-08-01 20:23:46,450] [Log    ] [12       ] [DEBUG] - WaitForPmt: PMT handling took 0.3900223 seconds.
[2024-08-01 20:23:46,462] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 5:30:00 AM to 8/2/2024 8:59:00 AM
[2024-08-01 20:23:46,472] [Log    ] [12       ] [INFO ] - dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
[2024-08-01 20:23:46,483] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 8:59:00 AM - 8/2/2024 8:59:00 AM)
[2024-08-01 20:23:46,496] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 9:00:00 AM to 8/2/2024 10:59:00 AM
[2024-08-01 20:23:46,507] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 10:59:00 AM - 8/2/2024 10:59:00 AM)
[2024-08-01 20:23:46,521] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 11:00:00 AM to 8/2/2024 11:59:00 AM
[2024-08-01 20:23:46,533] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 11:59:00 AM - 8/2/2024 11:59:00 AM)
[2024-08-01 20:23:46,543] [Log    ] [12       ] [DEBUG] - card: Tuner locked: True
[2024-08-01 20:23:46,553] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 12:00:00 PM to 8/2/2024 12:59:00 PM
[2024-08-01 20:23:46,563] [Log    ] [12       ] [INFO ] - **************************************************
[2024-08-01 20:23:46,573] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 12:59:00 PM - 8/2/2024 12:59:00 PM)
[2024-08-01 20:23:46,582] [Log    ] [12       ] [INFO ] - ***** SIGNAL LEVEL: 0, SIGNAL QUALITY: 100 *****
[2024-08-01 20:23:46,592] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 1:00:00 PM to 8/2/2024 3:59:00 PM
[2024-08-01 20:23:46,602] [Log    ] [12       ] [INFO ] - **************************************************
[2024-08-01 20:23:46,613] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 3:59:00 PM - 8/2/2024 3:59:00 PM)
[2024-08-01 20:23:46,623] [Log    ] [12       ] [DEBUG] - card: tuned user: htpc-i3 subchannel: 5
[2024-08-01 20:23:46,632] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 4:00:00 PM to 8/2/2024 6:59:00 PM
[2024-08-01 20:23:46,642] [Log    ] [12       ] [INFO ] - user:htpc-i3 add
[2024-08-01 20:23:46,652] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 6:59:00 PM - 8/2/2024 6:59:00 PM)
[2024-08-01 20:23:46,662] [Log    ] [12       ] [INFO ] - card2:htpc-i3 6 5
[2024-08-01 20:23:46,674] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 7:00:00 PM to 8/2/2024 9:59:00 PM
[2024-08-01 20:23:46,684] [Log    ] [12       ] [INFO ] - Controller: delete timeshift files F:\Users\Public\Recorded TV\TempRec\live6-5.ts
[2024-08-01 20:23:46,694] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 9:59:00 PM - 8/2/2024 9:59:00 PM)
[2024-08-01 20:23:46,704] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by event
[2024-08-01 20:23:46,724] [Log    ] [12       ] [INFO ] - Controller: epg stop, caller 1
[2024-08-01 20:23:46,734] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/2/2024 10:00:00 PM to 8/2/2024 11:59:00 PM
[2024-08-01 20:23:46,745] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2024-08-01 20:23:46,755] [Log    ] [12       ] [INFO ] - card: StartTimeShifting 6 F:\Users\Public\Recorded TV\TempRec\live6-5.ts
[2024-08-01 20:23:46,767] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/2/2024 11:59:00 PM - 8/2/2024 11:59:00 PM)
[2024-08-01 20:23:46,780] [Log    ] [12       ] [INFO ] - card: CAM enabled : False
[2024-08-01 20:23:46,790] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-01 20:23:46,800] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/3/2024 12:00:00 AM to 8/3/2024 2:59:00 AM
[2024-08-01 20:23:46,810] [Log    ] [12       ] [INFO ] - subch:5 SetTimeShiftFileName:F:\Users\Public\Recorded TV\TempRec\live6-5.ts
[2024-08-01 20:23:46,820] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-01 20:23:46,830] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/3/2024 2:59:00 AM - 8/3/2024 2:59:00 AM)
[2024-08-01 20:23:46,839] [Log    ] [12       ] [INFO ] - Set video / audio observer
[2024-08-01 20:23:46,850] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-01 20:23:46,861] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/3/2024 3:00:00 AM to 8/3/2024 7:59:00 AM
[2024-08-01 20:23:46,871] [Log    ] [12       ] [INFO ] - subch:5 SetTimeShiftFileName fill in pids
[2024-08-01 20:23:46,881] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-01 20:23:46,891] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/3/2024 7:59:00 AM - 8/3/2024 7:59:00 AM)
[2024-08-01 20:23:46,901] [Log    ] [12       ] [INFO ] - subch:5-5 tswriter StartTimeshifting...
[2024-08-01 20:23:46,912] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-01 20:23:46,921] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/3/2024 8:00:00 AM to 8/3/2024 9:59:00 AM
[2024-08-01 20:23:46,933] [Log    ] [12       ] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo
[2024-08-01 20:23:46,944] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: AwayModeRequested
[2024-08-01 20:23:46,953] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/3/2024 9:59:00 AM - 8/3/2024 9:59:00 AM)
[2024-08-01 20:23:46,963] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-01 20:23:46,975] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/3/2024 10:00:00 AM to 8/3/2024 9:04:00 PM
[2024-08-01 20:23:46,986] [Log    ] [22       ] [INFO ] - PID seen - type = Video
[2024-08-01 20:23:46,995] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(AwayModeRequested)
[2024-08-01 20:23:47,005] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/3/2024 9:04:00 PM - 8/3/2024 9:04:00 PM)
[2024-08-01 20:23:47,015] [Log    ] [22       ] [INFO ] - audioVideoEventHandler Video
[2024-08-01 20:23:47,025] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is busy and should not go to standby
[2024-08-01 20:23:47,171] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/3/2024 9:05:00 PM to 8/3/2024 11:59:00 PM
[2024-08-01 20:23:47,314] [Log    ] [22       ] [INFO ] - PID seen - type = Audio
[2024-08-01 20:23:47,457] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/3/2024 11:59:00 PM - 8/3/2024 11:59:00 PM)
[2024-08-01 20:23:47,601] [Log    ] [22       ] [INFO ] - audioVideoEventHandler Audio
[2024-08-01 20:23:47,747] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 12:00:00 AM to 8/4/2024 2:59:00 AM
[2024-08-01 20:23:47,893] [Log    ] [12       ] [INFO ] - card: WaitForFile - video and audio are seen after 0.8330477 seconds
[2024-08-01 20:23:48,037] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 2:59:00 AM - 8/4/2024 2:59:00 AM)
[2024-08-01 20:23:48,180] [Log    ] [12       ] [INFO ] - Timeshifting epg grabber started.
[2024-08-01 20:23:48,324] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 3:00:00 AM to 8/4/2024 7:59:00 AM
[2024-08-01 20:23:48,467] [Log    ] [12       ] [INFO ] - dvb:grab epg...
[2024-08-01 20:23:48,613] [Log    ] [20       ] [DEBUG] - PS: RegisterRemote tag: 4, uris: http://localhost:31458/6f17f3e0_16a8_4ada_abc6_5cb30c1df5c0/dbuuvboeudpyk6ptk5m+notu_1.rem, http://localhost:31458/6f17f3e0_16a8_4ada_abc6_5cb30c1df5c0/dbuuvboeudpyk6ptk5m+notu_1.rem
[2024-08-01 20:23:48,757] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 7:59:00 AM - 8/4/2024 7:59:00 AM)
[2024-08-01 20:23:48,900] [Log    ] [12       ] [INFO ] - user:htpc-i3 card:6 sub:5 add stream:F:\Users\Public\Recorded TV\TempRec\live6-5.ts.tsbuffer
[2024-08-01 20:23:49,045] [Log    ] [26       ] [DEBUG] - PS: Set time of last user activity to 8:23:46 PM
[2024-08-01 20:23:49,188] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 8:00:00 AM to 8/4/2024 12:59:00 PM
[2024-08-01 20:23:49,333] [Log    ] [12       ] [INFO ] - RTSP: add stream stream6.5 file:F:\Users\Public\Recorded TV\TempRec\live6-5.ts.tsbuffer
[2024-08-01 20:23:49,476] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 12:59:00 PM - 8/4/2024 12:59:00 PM)
[2024-08-01 20:23:49,637] [Log    ] [12       ] [INFO ] - Controller: StartTimeShifting started on card:6 to F:\Users\Public\Recorded TV\TempRec\live6-5.ts.tsbuffer
[2024-08-01 20:23:49,782] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 1:00:00 PM to 8/4/2024 1:59:00 PM
[2024-08-01 20:23:49,925] [Log    ] [12       ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=117, tuningdetails=DVBT:tv:Nine Network Australia 9HD Melbourne Freq:191625 ONID:4114 TSID:1072 SID:1073 PMT:0x101 FTA:True LCN:90 BandWidth:7
[2024-08-01 20:23:50,068] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 1:59:00 PM - 8/4/2024 1:59:00 PM)
[2024-08-01 20:23:50,211] [Log    ] [12       ] [INFO ] - card: SignalTuneEvent card: 6 / subch: 5
[2024-08-01 20:23:50,355] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 2:00:00 PM to 8/4/2024 7:59:00 PM
[2024-08-01 20:23:50,497] [Log    ] [12       ] [INFO ] - Controller: htpc-i3 6 5
[2024-08-01 20:23:50,642] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 7:59:00 PM - 8/4/2024 7:59:00 PM)
[2024-08-01 20:23:50,788] [Log    ] [12       ] [DEBUG] - CardReservation.CancelCardReservation id=118
[2024-08-01 20:23:50,932] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 8:00:00 PM to 8/4/2024 8:59:00 PM
[2024-08-01 20:23:51,074] [Log    ] [12       ] [INFO ] - control2:htpc-i3 6 5
[2024-08-01 20:23:51,218] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 8:59:00 PM - 8/4/2024 8:59:00 PM)
[2024-08-01 20:23:51,227] [Log    ] [12       ] [DEBUG] - RemoveUserFromOtherCards : htpc-i3 - 5
[2024-08-01 20:23:51,238] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 9:00:00 PM to 8/4/2024 9:59:00 PM
[2024-08-01 20:23:51,249] [Log    ] [12       ] [INFO ] - Controller: StopTimeShifting 5
[2024-08-01 20:23:51,259] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by event
[2024-08-01 20:23:51,276] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 9:59:00 PM - 8/4/2024 9:59:00 PM)
[2024-08-01 20:23:51,286] [Log    ] [12       ] [INFO ] - Controller: epg stop, caller 3
[2024-08-01 20:23:51,297] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2024-08-01 20:23:51,307] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/4/2024 10:00:00 PM to 8/4/2024 11:59:00 PM
[2024-08-01 20:23:51,318] [Log    ] [12       ] [DEBUG] - CardTuner.RequestCardStopReservation: placed reservation with id=119, user=htpc-i3
[2024-08-01 20:23:51,331] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/4/2024 11:59:00 PM - 8/4/2024 11:59:00 PM)
[2024-08-01 20:23:51,341] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-01 20:23:51,352] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 12:00:00 AM to 8/5/2024 2:59:00 AM
[2024-08-01 20:23:51,362] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-01 20:23:51,373] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 2:59:00 AM - 8/5/2024 2:59:00 AM)
[2024-08-01 20:23:51,384] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-01 20:23:51,395] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 3:00:00 AM to 8/5/2024 5:04:00 AM
[2024-08-01 20:23:51,405] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-01 20:23:51,415] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 5:04:00 AM - 8/5/2024 5:04:00 AM)
[2024-08-01 20:23:51,424] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-01 20:23:51,434] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 5:05:00 AM to 8/5/2024 5:29:00 AM
[2024-08-01 20:23:51,445] [Log    ] [12       ] [DEBUG] - CardTuner.Stop: ticket id=119, found=True
[2024-08-01 20:23:51,456] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: AwayModeRequested
[2024-08-01 20:23:51,466] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 5:29:00 AM - 8/5/2024 5:29:00 AM)
[2024-08-01 20:23:51,476] [Log    ] [12       ] [INFO ] - Stop cardid=5, ticket=119, tunestate=Idle, stopstate=Stopping
[2024-08-01 20:23:51,486] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-01 20:23:51,496] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 5:30:00 AM to 8/5/2024 8:59:00 AM
[2024-08-01 20:23:51,506] [Log    ] [12       ] [INFO ] - card Hauppauge WinTV-7164 BDA DVBT Tuner: StopTimeShifting user:htpc-i3 sub:0
[2024-08-01 20:23:51,516] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(AwayModeRequested)
[2024-08-01 20:23:51,526] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 8:59:00 AM - 8/5/2024 8:59:00 AM)
[2024-08-01 20:23:51,536] [Log    ] [12       ] [DEBUG] - card not IDLE - removing user: htpc-i3
[2024-08-01 20:23:51,548] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is busy and should not go to standby
[2024-08-01 20:23:51,693] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 9:00:00 AM to 8/5/2024 10:59:00 AM
[2024-08-01 20:23:51,837] [Log    ] [12       ] [DEBUG] - usermanagement.RemoveUser: htpc-i3, subch: 0 of 1, card: 5
[2024-08-01 20:23:51,981] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 10:59:00 AM - 8/5/2024 10:59:00 AM)
[2024-08-01 20:23:52,124] [Log    ] [12       ] [INFO ] - user:htpc-i3 remove
[2024-08-01 20:23:52,268] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 11:00:00 AM to 8/5/2024 11:59:00 AM
[2024-08-01 20:23:52,412] [Log    ] [12       ] [INFO ] - subch:0-0 tswriter StopTimeshifting...
[2024-08-01 20:23:52,556] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 11:59:00 AM - 8/5/2024 11:59:00 AM)
[2024-08-01 20:23:52,705] [Log    ] [12       ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
[2024-08-01 20:23:52,850] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 12:00:00 PM to 8/5/2024 12:59:00 PM
[2024-08-01 20:23:52,994] [Log    ] [12       ] [INFO ] - DVB subch:0 Decompose()
[2024-08-01 20:23:53,138] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 12:59:00 PM - 8/5/2024 12:59:00 PM)
[2024-08-01 20:23:53,281] [Log    ] [12       ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0
[2024-08-01 20:23:53,425] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 1:00:00 PM to 8/5/2024 3:59:00 PM
[2024-08-01 20:23:53,569] [Log    ] [12       ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph
[2024-08-01 20:23:53,717] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 3:59:00 PM - 8/5/2024 3:59:00 PM)
[2024-08-01 20:23:53,860] [Log    ] [12       ] [INFO ] - dvbtopGraph called
[2024-08-01 20:23:54,003] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 4:00:00 PM to 8/5/2024 6:59:00 PM
[2024-08-01 20:23:54,147] [Log    ] [12       ] [INFO ] - tvcard:FreeAllSubChannels
[2024-08-01 20:23:54,290] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 6:59:00 PM - 8/5/2024 6:59:00 PM)
[2024-08-01 20:23:54,432] [Log    ] [12       ] [INFO ] - dvbtopGraph
[2024-08-01 20:23:54,575] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 7:00:00 PM to 8/5/2024 8:59:00 PM
[2024-08-01 20:23:54,724] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 8:59:00 PM - 8/5/2024 8:59:00 PM)
[2024-08-01 20:23:54,867] [Log    ] [12       ] [INFO ] - debug: IMediaControl stopped! hr = 0x0
[2024-08-01 20:23:55,011] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 9:00:00 PM to 8/5/2024 9:59:00 PM
[2024-08-01 20:23:55,153] [Log    ] [12       ] [INFO ] - dvbtopGraph called
[2024-08-01 20:23:55,298] [Log    ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files F:\Users\Public\Recorded TV\TempRec\live5-0.ts
[2024-08-01 20:23:55,441] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 9:59:00 PM - 8/5/2024 9:59:00 PM)
[2024-08-01 20:23:55,583] [Log    ] [12       ] [INFO ] - tvcard:FreeAllSubChannels
[2024-08-01 20:23:55,734] [Log    ] [TS_File_Cleanup] [INFO ] - card:   trying to delete F:\Users\Public\Recorded TV\TempRec\live5-0.ts.tsbuffer
[2024-08-01 20:23:55,904] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/5/2024 10:00:00 PM to 8/5/2024 11:59:00 PM
[2024-08-01 20:23:56,047] [Log    ] [12       ] [INFO ] - dvbtopGraph filterstate already stopped, returning.
[2024-08-01 20:23:56,195] [Log    ] [TS_File_Cleanup] [INFO ] - card:   deleted file F:\Users\Public\Recorded TV\TempRec\live5-0.ts.tsbuffer
[2024-08-01 20:23:56,205] [EPG    ] [EPG Update thread] [INFO ] - - Deleted the epg entry Australia's home of country music (8/5/2024 11:59:00 PM - 8/5/2024 11:59:00 PM)
[2024-08-01 20:23:56,214] [Log    ] [12       ] [INFO ] - user:htpc-i3 remove
[2024-08-01 20:23:56,226] [Log    ] [TS_File_Cleanup] [INFO ] - card:   trying to delete F:\Users\Public\Recorded TV\TempRec\live5-0.ts.tsbuffer1.ts
[2024-08-01 20:23:56,236] [EPG    ] [EPG Update thread] [INFO ] - - 1 entries are obsolete for ABC Country from 8/6/2024 12:00:00 AM to 8/6/2024 2:59:00 AM
[2024-08-01 20:23:56,247] [Log    ] [12       ] [DEBUG] - CardReservation.RemoveStopTicket: removed STOP reservation with id=119
[2024-08-01 20:23:56,257] [Log    ] [12       ] [INFO ] - Controller:Timeshifting stopped on card:5
[2024-08-01 20:23:56,267] [Log    ] [12       ] [INFO ] - RTSP: remove stream stream5.0

-

 

[Hazza06]

i also noted a lot of TimeshiftingEpgGrabber grabbing for what looks like digital radio channels, so i've deleted all the radio channels ( don't use them anyway ), to see if it makes any improvement...to the DVB-T FTA TV channel changes....my initial testing, whilst very early and limited testing, of S3 sleep -> resume from S3 sleep, start MP, watch DVB-T FTA, and make some channel changes, repeat the test several times, is so far very encouraging....channel changes back to fast and so far no failures, but i've not made any definitive conclusions yet...will know more in coming days....

I also found the '[EPG Update thread] [ERROR] - Exception in Program.Persist()' always occurs when EPG update thread attempts to update digital radio channel 'ABC Country', examples below

$ egrep -ai -B 1 'Exception' tvserver_TVService.log
[2024-07-31 19:03:09,798] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Country lastUpdate:7/30/2024 8:40:29 PM
[2024-07-31 19:03:09,817] [Log    ] [EPG Update thread] [ERROR] - Exception in Program.Persist() with Message insert into Program ( idChannel, startTime, endTime, title, description, genre, state, originalAirDate, seriesNum, episodeNum, episodePart, episodeName, starRating, classification, parentalRating ) values ( ?idChannel, ?startTime, ?endTime, ?title, ?description, ?genre, ?state, ?originalAirDate, ?seriesNum, ?episodeNum, ?episodePart, ?episodeName, ?starRating, ?classification, ?parentalRating ); select LAST_INSERT_ID();
--
[2024-08-01 19:03:45,492] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Country lastUpdate:7/31/2024 7:03:12 PM
[2024-08-01 19:03:45,507] [Log    ] [EPG Update thread] [ERROR] - Exception in Program.Persist() with Message insert into Program ( idChannel, startTime, endTime, title, description, genre, state, originalAirDate, seriesNum, episodeNum, episodePart, episodeName, starRating, classification, parentalRating ) values ( ?idChannel, ?startTime, ?endTime, ?title, ?description, ?genre, ?state, ?originalAirDate, ?seriesNum, ?episodeNum, ?episodePart, ?episodeName, ?starRating, ?classification, ?parentalRating ); select LAST_INSERT_ID();
--
[2024-08-01 19:38:14,708] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Country lastUpdate:8/1/2024 7:03:48 PM
[2024-08-01 19:38:14,723] [Log    ] [EPG Update thread] [ERROR] - Exception in Program.Persist() with Message insert into Program ( idChannel, startTime, endTime, title, description, genre, state, originalAirDate, seriesNum, episodeNum, episodePart, episodeName, starRating, classification, parentalRating ) values ( ?idChannel, ?startTime, ?endTime, ?title, ?description, ?genre, ?state, ?originalAirDate, ?seriesNum, ?episodeNum, ?episodePart, ?episodeName, ?starRating, ?classification, ?parentalRating ); select LAST_INSERT_ID();
--
[2024-08-01 20:23:45,972] [EPG    ] [EPG Update thread] [INFO ] - TimeshiftingEpgGrabber: ABC Country lastUpdate:8/1/2024 7:38:20 PM
[2024-08-01 20:23:45,987] [Log    ] [EPG Update thread] [ERROR] - Exception in Program.Persist() with Message insert into Program ( idChannel, startTime, endTime, title, description, genre, state, originalAirDate, seriesNum, episodeNum, episodePart, episodeName, starRating, classification, parentalRating ) values ( ?idChannel, ?startTime, ?endTime, ?title, ?description, ?genre, ?state, ?originalAirDate, ?seriesNum, ?episodeNum, ?episodePart, ?episodeName, ?starRating, ?classification, ?parentalRating ); select LAST_INSERT_ID();

$

There must be something in ABC Country EPG that EPG Update thread....really can't handle and throws an exception....

 

[Hazza06]

issue confirmed NOT resolved, MP1.34 channel change issues continue, latest example below, changing channel to SBS VICELAND HD, fails

Did anybody do much DVB-T channel change testing, after a few cycles of S3 sleep / resume ?

I may have to roll back, as the WAF (wife approval factor) is dead, and she's ready to throw it out....would open heart surgery help ?? a complete uninstall / clean out of everything MP, then clean re-install resolve the issue ?????

i'll need to do something, as wife has plugged in FetchTV STB to replace MP HTPC....not acceptable......ugh FetchTV, yikes even more nightmares....so slow in GUI, i could almost eat a cut lunch by the time FetchTV responds....and crap LAN side media playback / crap codec support....

from MediaPortal.log

[2024-08-02 13:34:59,913] [Log    ] [MPMain   ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=SBS VICELAND HD
[2024-08-02 13:35:02,919] [Log    ] [MPMain   ] [INFO ] - TSReaderPlayer:Pause graph
[2024-08-02 13:35:02,936] [Log    ] [MPMain   ] [INFO ] - TSReaderPlayer: OnZapping :128
[2024-08-02 13:35:13,458] [Log    ] [MPMain   ] [ERROR] - TvServer: Failed to execute remote TV server operation (StartTimeShifting.2) A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond

from tvserver_TVService.log

[2024-08-02 13:34:59,995] [Log    ] [20       ] [INFO ] - Controller: TimeShiftingWouldUseCard SBS VICELAND HD 157
[2024-08-02 13:35:00,110] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-02 13:35:00,218] [Log    ] [20       ] [DEBUG] - GetFreeCardsForChannel SBS VICELAND HD
[2024-08-02 13:35:00,338] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-02 13:35:00,446] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel: find card for channel SBS VICELAND HD
[2024-08-02 13:35:00,558] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-02 13:35:00,664] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   got 1 tuning details for SBS VICELAND HD
[2024-08-02 13:35:00,774] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-02 13:35:00,880] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   channel #1 DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:00,984] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-02 13:35:01,089] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:1 type:RadioWebStream is disabled
[2024-08-02 13:35:01,197] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-02 13:35:01,314] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:2 type:DvbIP is disabled
[2024-08-02 13:35:01,421] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyPrevented)
[2024-08-02 13:35:01,528] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:3 type:Analog is disabled
[2024-08-02 13:35:01,635] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is busy and should not go to standby
[2024-08-02 13:35:01,745] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:4 type:Analog is disabled
[2024-08-02 13:35:01,852] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   card:5 type:DvbT can tune to channel
[2024-08-02 13:35:01,957] [Log    ] [20       ] [INFO ] - NumberOfOtherUsersOnCurrentCard:    card:5 type:DvbT users: 0
[2024-08-02 13:35:02,062] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   card:6 type:DvbT can tune to channel
[2024-08-02 13:35:02,166] [Log    ] [20       ] [INFO ] - NumberOfOtherUsersOnCurrentCard:    card:6 type:DvbT users: 0
[2024-08-02 13:35:02,270] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel: found 2 card(s) for channel
[2024-08-02 13:35:02,382] [Log    ] [20       ] [DEBUG] - GetAvailableCardsForChannel took 2044 msec
[2024-08-02 13:35:02,488] [Log    ] [20       ] [INFO ] - GetFreeCardsForChannel found 2 free card(s), channel: SBS VICELAND HD, user:htpc-i3
[2024-08-02 13:35:02,597] [Log    ] [20       ] [DEBUG] - GetFreeCardsForChannel, free card:0, id:6, STCA:False, ST:False, PRI:6, CL:1, NOU:0
[2024-08-02 13:35:02,701] [Log    ] [20       ] [DEBUG] - GetFreeCardsForChannel, free card:1, id:5, STCA:False, ST:False, PRI:5, CL:1, NOU:0
[2024-08-02 13:35:02,806] [Log    ] [20       ] [DEBUG] - GetFreeCardsForChannel took 2696 msec
[2024-08-02 13:35:02,948] [Log    ] [20       ] [INFO ] - Controller: StartTimeShifting SBS VICELAND HD 157
[2024-08-02 13:35:03,050] [Log    ] [20       ] [INFO ] - Controller: epg stop, caller 5
[2024-08-02 13:35:03,153] [Log    ] [20       ] [DEBUG] - GetFreeCardsForChannel SBS VICELAND HD
[2024-08-02 13:35:03,254] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel: find card for channel SBS VICELAND HD
[2024-08-02 13:35:03,368] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   got 1 tuning details for SBS VICELAND HD
[2024-08-02 13:35:03,469] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   channel #1 DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:03,572] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:1 type:RadioWebStream is disabled
[2024-08-02 13:35:03,675] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:2 type:DvbIP is disabled
[2024-08-02 13:35:03,778] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:3 type:Analog is disabled
[2024-08-02 13:35:03,882] [Log    ] [20       ] [INFO ] - CanCardTuneChannel:            card:4 type:Analog is disabled
[2024-08-02 13:35:03,986] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   card:5 type:DvbT can tune to channel
[2024-08-02 13:35:04,090] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel:   card:6 type:DvbT can tune to channel
[2024-08-02 13:35:04,194] [Log    ] [20       ] [INFO ] - GetAvailableCardsForChannel: found 2 card(s) for channel
[2024-08-02 13:35:04,302] [Log    ] [20       ] [DEBUG] - GetAvailableCardsForChannel took 1047 msec
[2024-08-02 13:35:04,416] [Log    ] [20       ] [INFO ] - GetFreeCardsForChannel found 2 available card(s), channel: SBS VICELAND HD, user:htpc-i3
[2024-08-02 13:35:04,518] [Log    ] [20       ] [DEBUG] - GetFreeCardsForChannel took 1365 msec
[2024-08-02 13:35:04,625] [Log    ] [20       ] [DEBUG] - RequestCardTuneReservation: placed reservation with id=43, tuningdetails=DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:04,728] [Log    ] [20       ] [DEBUG] - RequestCardTuneReservation: placed reservation with id=44, tuningdetails=DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:04,831] [Log    ] [20       ] [DEBUG] - UpdateFreeCardsForChannelBasedOnTicket: user: htpc-i3
[2024-08-02 13:35:04,937] [Log    ] [10       ] [DEBUG] - PS: GetCurrentState - Reset "Finished" and triggered StandbyWakeupThread
[2024-08-02 13:35:05,039] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by event
[2024-08-02 13:35:05,053] [Log    ] [20       ] [INFO ] - UpdateFreeCardsForChannelBasedOnTicket found 2 free card(s), user:htpc-i3
[2024-08-02 13:35:05,064] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2024-08-02 13:35:05,074] [Log    ] [20       ] [DEBUG] - UpdateFreeCardsForChannelBasedOnTicket, free card:0, id:6, STCA:False, ST:False, PRI:6, CL:1, NOU:0
[2024-08-02 13:35:05,088] [Log    ] [20       ] [DEBUG] - UpdateFreeCardsForChannelBasedOnTicket, free card:1, id:5, STCA:False, ST:False, PRI:5, CL:1, NOU:0
[2024-08-02 13:35:05,099] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-02 13:35:05,110] [Log    ] [20       ] [INFO ] - Controller: try max 2 of 2 cards for timeshifting
[2024-08-02 13:35:05,121] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-02 13:35:05,131] [Log    ] [20       ] [DEBUG] - CardReservationBase.CardTune: tvcard=6, user=htpc-i3, dbChannel=157, ticket=43, tunestate=Tuning, stopstate=Idle, ticketFTA=True
[2024-08-02 13:35:05,142] [Log    ] [10       ] [DEBUG] - PS: GetCurrentState - StandbyWakeupThread signaled "Finished"
[2024-08-02 13:35:05,154] [Log    ] [20       ] [INFO ] - CardTuner.CardTune: 6 SBS VICELAND HD htpc-i3:6:-1
[2024-08-02 13:35:05,168] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-02 13:35:05,181] [Log    ] [20       ] [INFO ] - CardTuner.Tune: Tune on card 6 to subchannel SBS VICELAND HD
[2024-08-02 13:35:05,192] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-02 13:35:05,202] [Log    ] [20       ] [DEBUG] - CardTuner.BeforeTune: user: htpc-i3:6:0 tune DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:05,212] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-02 13:35:05,226] [Log    ] [20       ] [DEBUG] - CardTuner.BeforeTune: change to different transponder
[2024-08-02 13:35:05,238] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-02 13:35:05,248] [Log    ] [20       ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
[2024-08-02 13:35:05,259] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyPrevented)
[2024-08-02 13:35:05,270] [Log    ] [20       ] [INFO ] - tvcard:FreeSubChannel :0 - is timeshifting (skipped)
[2024-08-02 13:35:05,280] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is busy and should not go to standby
[2024-08-02 13:35:05,392] [Log    ] [20       ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events
[2024-08-02 13:35:05,495] [Log    ] [20       ] [INFO ] - dvbt: Tune:DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:05,598] [Log    ] [20       ] [INFO ] - dvb:Submiting tunerequest Channel:SBS VICELAND HD subChannel:0
[2024-08-02 13:35:05,701] [Log    ] [20       ] [INFO ] - dvb:using existing subchannel:0
[2024-08-02 13:35:05,804] [Log    ] [20       ] [INFO ] - dvb:Submit tunerequest size:1 new:0
[2024-08-02 13:35:05,909] [Log    ] [20       ] [INFO ] - subch:0 OnBeforeTune
[2024-08-02 13:35:06,012] [Log    ] [20       ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest
[2024-08-02 13:35:06,910] [Log    ] [20       ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest
[2024-08-02 13:35:07,016] [Log    ] [20       ] [INFO ] - subch:0 OnAfterTune
[2024-08-02 13:35:07,119] [Log    ] [20       ] [INFO ] - RunGraph
[2024-08-02 13:35:07,240] [Log    ] [20       ] [INFO ] - dvb:  LockInOnSignal ok
[2024-08-02 13:35:07,344] [Log    ] [20       ] [INFO ] - subch:0 OnGraphStart
[2024-08-02 13:35:07,455] [Log    ] [25       ] [DEBUG] - PS: RegisterRemote tag: 5, uris: http://localhost:31458/0dbe93cc_a850_415d_ab7d_276dc6163aa5/vbwskpt8vwwef1fdzq9zqewg_1.rem, http://localhost:31458/0dbe93cc_a850_415d_ab7d_276dc6163aa5/vbwskpt8vwwef1fdzq9zqewg_1.rem
[2024-08-02 13:35:07,558] [Log    ] [20       ] [INFO ] - subch:0 Graph already running - WaitForPMT
[2024-08-02 13:35:07,661] [Log    ] [25       ] [DEBUG] - PS: Set time of last user activity to 1:35:05 PM
[2024-08-02 13:35:07,764] [Log    ] [20       ] [INFO ] - subch:0 SetupPmtGrabber:pid 40C sid:316
[2024-08-02 13:35:07,871] [Log    ] [20       ] [INFO ] - subch:0 set pmt grabber pmt:40C sid:316
[2024-08-02 13:35:07,974] [Log    ] [20       ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time
[2024-08-02 13:35:08,077] [Log    ] [21       ] [INFO ] - subch:0 OnPMTReceived() pmt:40C ran:True dynamic:False
[2024-08-02 13:35:08,179] [Log    ] [20       ] [DEBUG] - WaitForPMT: Waiting for PMT 40C
[2024-08-02 13:35:08,283] [Log    ] [20       ] [DEBUG] - WaitForPMT: Found PMT after 0.2060118 seconds.
[2024-08-02 13:35:08,391] [Log    ] [20       ] [INFO ] - subch:0 SendPmt:316 316 FFFFFFFF 2
[2024-08-02 13:35:08,497] [Log    ] [20       ] [INFO ] - Decode pmt
[2024-08-02 13:35:08,599] [Log    ] [20       ] [INFO ] - Set descriptor data with length 7
[2024-08-02 13:35:08,702] [Log    ] [20       ] [INFO ] - Set descriptor data with length 6
[2024-08-02 13:35:08,805] [Log    ] [20       ] [INFO ] - Set descriptor data with length 3
[2024-08-02 13:35:08,910] [Log    ] [20       ] [INFO ] - PROBLEM : descriptor lengths dont match 6 9
[2024-08-02 13:35:09,014] [Log    ] [20       ] [INFO ] - subch:0 SendPMT: Channel is FTA, nothing to do.
[2024-08-02 13:35:09,117] [Log    ] [20       ] [INFO ] - subch:0 SetMpegPidMapping
[2024-08-02 13:35:09,220] [Log    ] [20       ] [INFO ] - subch:0  pid:A6 pcr
[2024-08-02 13:35:09,323] [Log    ] [20       ] [INFO ] - subch:0  pid:40C pmt
[2024-08-02 13:35:09,437] [Log    ] [20       ] [INFO ] - subch:0  pid:2E teletext type:6
[2024-08-02 13:35:09,539] [Log    ] [20       ] [INFO ] - subch:0    map pid:2E teletext type:6
[2024-08-02 13:35:09,642] [Log    ] [20       ] [INFO ] - subch:0  pid:56 audio lang:eng type:MPEG-1
[2024-08-02 13:35:09,744] [Log    ] [20       ] [INFO ] - subch:0    map pid:56 audio lang:eng type:MPEG-1
[2024-08-02 13:35:09,847] [Log    ] [20       ] [INFO ] - subch:0  pid:58 audio lang: type:LATM AAC
[2024-08-02 13:35:09,953] [Log    ] [20       ] [INFO ] - subch:0  pid:A6 video type:H.264
[2024-08-02 13:35:10,056] [Log    ] [20       ] [INFO ] - subch:0    map pid:A6 video type:H.264
[2024-08-02 13:35:10,159] [Log    ] [20       ] [INFO ] - subch:0  pid:1B58 type:B
[2024-08-02 13:35:10,263] [Log    ] [10       ] [DEBUG] - PS: GetCurrentState - Reset "Finished" and triggered StandbyWakeupThread
[2024-08-02 13:35:10,274] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by event
[2024-08-02 13:35:10,288] [Log    ] [20       ] [INFO ] - subch:0  pid:1E6E type:5
[2024-08-02 13:35:10,299] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2024-08-02 13:35:10,309] [Log    ] [20       ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed
[2024-08-02 13:35:10,327] [Log    ] [21       ] [INFO ] - PID seen - type = Video
[2024-08-02 13:35:10,338] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-02 13:35:10,349] [Log    ] [20       ] [INFO ] - subch:0 stop tif
[2024-08-02 13:35:10,360] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-02 13:35:10,371] [Log    ] [21       ] [INFO ] - audioVideoEventHandler Video
[2024-08-02 13:35:10,381] [Log    ] [20       ] [DEBUG] - WaitForPmt: PMT handling took 1.9681126 seconds.
[2024-08-02 13:35:10,392] [Log    ] [10       ] [DEBUG] - PS: GetCurrentState - StandbyWakeupThread signaled "Finished"
[2024-08-02 13:35:10,403] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-02 13:35:10,423] [Log    ] [20       ] [INFO ] - dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
[2024-08-02 13:35:10,438] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-02 13:35:10,448] [Log    ] [21       ] [INFO ] - video last seen at 8/2/2024 1:35:10 PM
[2024-08-02 13:35:10,459] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-02 13:35:10,469] [Log    ] [21       ] [INFO ] - video last seen at 8/2/2024 1:35:10 PM
[2024-08-02 13:35:10,480] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-02 13:35:10,491] [Log    ] [21       ] [INFO ] - video last seen at 8/2/2024 1:35:10 PM
[2024-08-02 13:35:10,501] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyPrevented)
[2024-08-02 13:35:10,512] [Log    ] [20       ] [DEBUG] - card: Tuner locked: True
[2024-08-02 13:35:10,522] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is busy and should not go to standby
[2024-08-02 13:35:10,624] [Log    ] [20       ] [INFO ] - **************************************************
[2024-08-02 13:35:10,726] [Log    ] [21       ] [INFO ] - video last seen at 8/2/2024 1:35:10 PM
[2024-08-02 13:35:10,828] [Log    ] [20       ] [INFO ] - ***** SIGNAL LEVEL: 0, SIGNAL QUALITY: 100 *****
[2024-08-02 13:35:10,932] [Log    ] [21       ] [INFO ] - PID seen - type = Audio
[2024-08-02 13:35:11,035] [Log    ] [20       ] [INFO ] - **************************************************
[2024-08-02 13:35:11,138] [Log    ] [21       ] [INFO ] - audioVideoEventHandler Audio
[2024-08-02 13:35:11,241] [Log    ] [20       ] [DEBUG] - card: tuned user: htpc-i3 subchannel: 0
[2024-08-02 13:35:11,346] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:11 PM
[2024-08-02 13:35:11,450] [Log    ] [20       ] [INFO ] - user:htpc-i3 add
[2024-08-02 13:35:11,554] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:11 PM
[2024-08-02 13:35:11,657] [Log    ] [20       ] [INFO ] - card2:htpc-i3 6 0
[2024-08-02 13:35:11,759] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:11 PM
[2024-08-02 13:35:11,862] [Log    ] [20       ] [INFO ] - Controller: epg stop, caller 1
[2024-08-02 13:35:11,964] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by event
[2024-08-02 13:35:12,069] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:11 PM
[2024-08-02 13:35:12,170] [Log    ] [20       ] [INFO ] - card: StartTimeShifting 6 F:\Users\Public\Recorded TV\TempRec\live6-0.ts
[2024-08-02 13:35:12,285] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2024-08-02 13:35:12,387] [Log    ] [20       ] [INFO ] - card: CAM enabled : False
[2024-08-02 13:35:12,494] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-02 13:35:12,596] [Log    ] [21       ] [INFO ] - PID seen - type = Audio
[2024-08-02 13:35:12,699] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-02 13:35:12,800] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:12 PM
[2024-08-02 13:35:12,904] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-02 13:35:13,006] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:12 PM
[2024-08-02 13:35:13,108] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-02 13:35:13,210] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:13 PM
[2024-08-02 13:35:13,313] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-02 13:35:13,415] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:13 PM
[2024-08-02 13:35:13,524] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-02 13:35:13,648] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:13 PM
[2024-08-02 13:35:13,750] [Log    ] [15       ] [INFO ] - Controller: StopTimeShifting 6
[2024-08-02 13:35:13,852] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyPrevented)
[2024-08-02 13:35:13,958] [Log    ] [21       ] [INFO ] - audio last seen at 8/2/2024 1:35:13 PM
[2024-08-02 13:35:14,060] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: failed reservation user=htpc-i3, cardstate=Tuning
[2024-08-02 13:35:14,180] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is busy and should not go to standby
[2024-08-02 13:35:14,289] [Log    ] [20       ] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo
[2024-08-02 13:35:14,396] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: failed reservation user=htpc-i3, cardstate=Tuning
[2024-08-02 13:35:14,512] [Log    ] [20       ] [INFO ] - card: WaitForFile - video and audio are seen after 0.3350191 seconds
[2024-08-02 13:35:14,616] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: failed reservation user=htpc-i3, cardstate=Tuning
[2024-08-02 13:35:14,718] [Log    ] [20       ] [INFO ] - Timeshifting epg grabber started.
[2024-08-02 13:35:14,821] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: failed reservation user=htpc-i3, cardstate=Tuning
[2024-08-02 13:35:14,925] [Log    ] [20       ] [INFO ] - dvb:grab epg...
[2024-08-02 13:35:15,027] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: failed reservation user=htpc-i3, cardstate=Tuning
[2024-08-02 13:35:15,129] [Log    ] [20       ] [INFO ] - Controller: StartTimeShifting started on card:6 to F:\Users\Public\Recorded TV\TempRec\live6-0.ts
[2024-08-02 13:35:15,232] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: failed reservation user=htpc-i3, cardstate=Tuning
[2024-08-02 13:35:15,335] [Log    ] [20       ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=43, tuningdetails=DVBT:tv:SBS SBS VICELAND HD Freq:184500 ONID:12802 TSID:784 SID:790 PMT:0x40C FTA:True LCN:31 BandWidth:7
[2024-08-02 13:35:15,438] [Log    ] [10       ] [DEBUG] - PS: GetCurrentState - Reset "Finished" and triggered StandbyWakeupThread
[2024-08-02 13:35:15,550] [Log    ] [15       ] [DEBUG] - CardTuner.RequestCardStopReservation: placed reservation with id=45, user=htpc-i3
[2024-08-02 13:35:15,653] [Log    ] [20       ] [INFO ] - Controller: htpc-i3 6 0
[2024-08-02 13:35:15,756] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by event
[2024-08-02 13:35:15,862] [Log    ] [10       ] [DEBUG] - PS: GetCurrentState - StandbyWakeupThread signaled "Finished"
[2024-08-02 13:35:15,970] [Log    ] [15       ] [DEBUG] - CardTuner.Stop: ticket id=45, found=True
[2024-08-02 13:35:16,075] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2024-08-02 13:35:16,177] [Log    ] [15       ] [INFO ] - Stop cardid=6, ticket=45, tunestate=Idle, stopstate=Stopping
[2024-08-02 13:35:16,284] [Log    ] [20       ] [DEBUG] - CardReservation.CancelCardReservation id=44
[2024-08-02 13:35:16,388] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2024-08-02 13:35:16,490] [Log    ] [15       ] [INFO ] - card Hauppauge WinTV-7164 BDA DVBT Tuner: StopTimeShifting user:htpc-i3 sub:0
[2024-08-02 13:35:16,607] [Log    ] [20       ] [INFO ] - control2:htpc-i3 6 0
[2024-08-02 13:35:16,710] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: 8/2/2024 6:00:00 PM
[2024-08-02 13:35:16,813] [Log    ] [15       ] [DEBUG] - card not IDLE - removing user: htpc-i3
[2024-08-02 13:35:16,918] [Log    ] [3        ] [DEBUG] - Controller: DoSetChannelStates for 31 channels
[2024-08-02 13:35:17,021] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 8/2/2024 5:59:00 PM
[2024-08-02 13:35:17,124] [Log    ] [15       ] [DEBUG] - usermanagement.RemoveUser: htpc-i3, subch: 0 of 1, card: 6
[2024-08-02 13:35:17,227] [Log    ] [3        ] [INFO ] - ChannelStates.GetAllTimeshiftingAndRecordingChannels took 0 msec
[2024-08-02 13:35:17,330] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2024-08-02 13:35:17,449] [Log    ] [15       ] [INFO ] - user:htpc-i3 remove
[2024-08-02 13:35:17,562] [Log    ] [3        ] [INFO ] - ChannelStates.DoSetChannelStates took 687 msec
[2024-08-02 13:35:17,667] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting TV Controller (Local timeshifting): StandbyPrevented
[2024-08-02 13:35:17,770] [Log    ] [15       ] [INFO ] - subch:0-0 tswriter StopTimeshifting...
[2024-08-02 13:35:17,874] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting EPG Grabbing: AwayModeRequested
[2024-08-02 13:35:17,891] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Client Plugin (Media playing): StandbyPrevented
[2024-08-02 13:35:17,902] [Log    ] [15       ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0

 

[CyberSimian]

After successfully resuming from ACPI S3 sleep state, Often channel changes are extremely slow now after the upgrade to MP 1.34, and also often channel changes fail altogether...

I regret that I cannot offer any advice (this problem is way above my pay grade), but @Pablik may be able to help. However ...

He will almost certainly require a full set of logs. Please use the MP Log Collector tool to gather all relevant files into a zip file, and then upload the zip file to this thread (use the "Attach files" button). Please ensure that the logs contain at least one example of the problem.

-- from CyberSimian in the UK

 

[Hazza06]

I regret that I cannot offer any advice (this problem is way above my pay grade), but @Pablik may be able to help. However ...

He will almost certainly require a full set of logs. Please use the MP Log Collector tool to gather all relevant files into a zip file, and then upload the zip file to this thread (use the "Attach files" button). Please ensure that the logs contain at least one example of the problem.

-- from CyberSimian in the UK

thanks for responding and showing an interest, let me collect the complete set of logs..

i suspect the timeshift EPG grabber could be an issue, as an isolation test, i'll disable timeshift EPG grabbing for a few days, see if it makes any difference...when i can get some screen time away from that darn FetchTV STB...

 

[CyberSimian]

i suspect the timeshift EPG grabber could be an issue, as an isolation test, i'll disable timeshift EPG grabbing for a few days, see if it makes any difference

Are you using the EPG data that is broadcast with the TV signals? This is the DVB EPG.

MP provides various settings for controlling the DVB EPG (because different countries in the world organise their DVB EPGs in different ways, and users have different requirements), and it is easy to end up with a less-than optimal combination of settings. For example, I would not recommend enabling both the timeshift EPG grabber and the idle EPG grabber; use one or the other, but not both. There are other settings that are important, too. See this page in the MP Wiki.

-- from CyberSimian in the UK

 

[joecrow]

Just a thought if you are using Madvr??? If not ignore this.
I put my HTPC to sleep after every evening viewing and wake it up the next day and I source TV via a TBS quad DVBT2 tuner. I have noted that channel changing had become very slow following the 1.33/4 and x64 upgrades. but no worsening or change relating to sleep. My MP is 1.34 x64 and I was using the Madvr renderer with D3D11 hardware decoder, although I never actually had a channel change failure just very slow. Changing the hardware decoder to DAVX2 (copy back), which was better in the past, did not help and I have used the option " Use EVR for Live TV" to get channel switch times back to a resonabley fast level. See setting in screen shot below.

 

[Hazza06]

Just a thought if you are using Madvr??? If not ignore this.
I put my HTPC to sleep after every evening viewing and wake it up the next day and I source TV via a TBS quad DVBT2 tuner. I have noted that channel changing had become very slow following the 1.33/4 and x64 upgrades. but no worsening or change relating to sleep. My MP is 1.34 x64 and I was using the Madvr renderer with D3D11 hardware decoder, although I never actually had a channel change failure just very slow. Changing the hardware decoder to DAVX2 (copy back), which was better in the past, did not help and I have used the option " Use EVR for Live TV" to get channel switch times back to a resonabley fast level. See setting in screen shot below.
View attachment 214212

thanks for this input, i use EVR with LAV filters/codecs, never used Madvr, i've not have had any rendering or codec issues, just DVB-T channel change issues after resume from ACPI S3 sleep state, since upgrading to MP1.34, channel change was rock solid reliable and fast with MP 1.31, and many earlier releases, as i said, i'm a long term MP enthusiast. The more i think about it, the more i suspect timeshift ( or idle ) EPG grabbing....may be the culprit

Are you using the EPG data that is broadcast with the TV signals? This is the DVB EPG.

MP provides various settings for controlling the DVB EPG (because different countries in the world organise their DVB EPGs in different ways, and users have different requirements), and it is easy to end up with a less-than optimal combination of settings. For example, I would not recommend enabling both the timeshift EPG grabber and the idle EPG grabber; use one or the other, but not both. There are other settings that are important, too. See this page in the MP Wiki.

-- from CyberSimian in the UK

Yes i use DVB-T EIT EPG, and there was no change whatsoever to epg grabbing config before or after the upgrade., i'll show a screenshot shortly, at AFL half time, and the full logs, we are watching AFL right at the moment, and it's beer-o-clock...

The only thing i did as mentioned above was to delete all DVB-T FTA channels and re-scan, which was without issue, then later deleted all digital radio channels, as [EPG Update thread] [ERROR] - Exception in Program.Persist() with Message insert into Program was occurring, see above post here -> 1.34 - major issues with DVB-T channel change after upgrading to MP 1.34

 

[CyberSimian]

there was no change whatsoever to epg grabbing config before or after the upgrade.

That suggests that it is not the EPG grabbing that is causing the problem. However ...

The only thing i did as mentioned above was to delete all DBV-T FTV channels and re-scan

When I rescan, I always select the Enable channel movement detection setting, and don't delete the existing channels. This preserves such things as channel-name edits, channel-selection status (channels that you had previously deselected will remain deselected), and the existing recording schedule. Deleting all of the channels before the scan preserves none of these things.

What I don't know is what effect deleting all of the channels has on the DVB EPG settings, in particular the channel(s) that you have selected to grab the EPG from. So it could still be a problem with the DVB EPG grabber settings.

-- from CyberSimian in the UK

 

[Hazza06]

okie, full set of logs as attached.

DBV-T EIT EPG grabbing config as below, before and after the upgrade

I also found, that mediaportal instance was not quitting, as below, the MP GUI definitely quits, however. evidently the mediaportal client process does not, so i have a few more tshooting leads to look into....i also have MP 1.34 client on LAN, that also experiences this issue, and that client is on update Win 10 x64, using 32 bit MP 1.34, so this is interesting....

Our usage pattern, for many year's is to quit MP, wait a few seconds, then put to ACPI S3 sleep
then resume from S3, start MP and watch LAN side CIFS connected content, or DVB-T FTA
then once finished, again quit MP, wait a few seconds, then put to ACPI S3 sleep

usage cycle repeats...