DVB EPG grabbing is "blocking" tuner card (1 Viewer)

Razorblade

Portal Pro
May 22, 2010
99
3
Home Country
Germany Germany
Hello,

I have an issue with DVB EPG grabbing.
After grabbing EPG data it happens that I am unable to start watching TV. It seems as if somehow my tuner card is blocked although MP says that the card is unused.

To resolve this I have to restart the server as I am unable to restart the service.

This is what I have tried so far:

Disable CRC check in EPG
Disable EPG for certain channels
Swap tuner cards

Only disabling DVB TV EPG completely seems to work.
I am able to watch and record shows and so on, so it seems as if there's an issue with DVB EPG grabbing.

The TV server itself is virtualized on a vSphere 5 server.

EPG_Log_1 (EPG until 10:58, TV from 19:47)
Code:
2012-05-29 10:58:48.053908 [(18)]: dvb:mhw ready MHW 0 titles found
2012-05-29 10:58:48.053908 [(18)]: dvb:dvb ready.EPG 0 channels
2012-05-29 10:58:48.053908 [(18)]: Epg: card:1005 no epg found
2012-05-29 10:58:48.053908 [(18)]: EpgGrabbing: Stop - user epg
2012-05-29 10:58:58.583926 [DVB EPG timer(4)]: Grab for card:#1005 transponder #3/16 channel: Bayerisches FS Süd
2012-05-29 10:58:58.583926 [DVB EPG timer(4)]: EpgCard: grab epg on card: #1005 transponder: #2 ch:Bayerisches FS Süd 
2012-05-29 19:47:39.554841 [(5)]: EPG: grabber stopped..
2012-05-29 19:47:39.554841 [(5)]: EpgCard: card: 1005 stop grabbing
2012-05-29 19:47:39.554841 [(5)]: EpgGrabbing: Stop - user epg
2012-05-29 20:00:11.818462 [TVService(6)]: EPG: grabber initialized for 16 transponders..
2012-05-29 20:00:13.050864 [DVB EPG timer(12)]: Grab for card:#1006 transponder #1/16 channel: RTL Television
2012-05-29 20:00:13.050864 [DVB EPG timer(12)]: EpgCard: grab epg on card: #1006 transponder: #0 ch:RTL Television 
2012-05-29 20:00:42.909316 [DVB EPG timer(4)]: Grab for card:#1006 transponder #2/16 channel: SAT 1
2012-05-29 20:00:42.909316 [DVB EPG timer(4)]: EpgCard: grab epg on card: #1006 transponder: #1 ch:SAT 1 
2012-05-29 20:00:48.728126 [DVB EPG timer(4)]: EpgGrabbing: Start
2012-05-29 20:00:48.743726 [DVB EPG timer(4)]: EpgCard: card: 1006 starting to grab DVBC:tv:Pro7 & Sat.1 SAT 1 Freq:121000 ONID:40984 TSID:1107 SID:17500 PMT:0xC9 FTA:True LCN:657 SymbolRate:6900 Modulation:Mod64Qam
2012-05-29 20:01:18.758179 [(4)]: EpgCard: allow grabbing for 30,0144527 seconds on card 1006
2012-05-29 20:01:36.776211 [(5)]: EPG: grabber stopped..
2012-05-29 20:01:36.776211 [(5)]: EpgCard: card: 1006 stop grabbing
2012-05-29 20:01:36.776211 [(5)]: EpgGrabbing: Stop - user epg
2012-05-29 20:01:37.727812 [(5)]: epg cancelled due to start timeshifting
2012-05-29 20:01:37.727812 [(5)]: epg grabber:epg cancelled
2012-05-29 20:01:37.727812 [(5)]: EpgGrabbing: Stop - user epg


tv.log_1 (EPG until 10:58, TV from 19:47)
Code:
012-05-29 10:48:58.310472 [(21)]: card: Tune 1005 to RTL Television
2012-05-29 10:48:58.310472 [(21)]: card: user: epg:1005:-1 tune DVBC:tv:RTL RTL Television Freq:113000 ONID:40984 TSID:1089 SID:12003 PMT:0x65 FTA:True LCN:650 SymbolRate:6900 Modulation:Mod64Qam
2012-05-29 10:48:58.310472 [(21)]: dvbc: Tune:DVBC:tv:RTL RTL Television Freq:113000 ONID:40984 TSID:1089 SID:12003 PMT:0x65 FTA:True LCN:650 SymbolRate:6900 Modulation:Mod64Qam
2012-05-29 10:48:58.310472 [(21)]: dvb:Submiting tunerequest Channel:RTL Television subChannel:-1 
2012-05-29 10:48:58.310472 [(21)]: dvb:Getting new subchannel
2012-05-29 10:48:58.310472 [(21)]: dvb:GetNewSubChannel:0 #0
2012-05-29 10:48:58.326072 [(21)]: TvDvbChannel ctor new subchIndex:0
2012-05-29 10:48:58.326072 [(21)]: dvb:Submit tunerequest size:1 new:0
2012-05-29 10:48:58.326072 [(21)]: subch:0 OnBeforeTune
2012-05-29 10:48:58.326072 [(21)]: dvb:Submit tunerequest calling put_TuneRequest
2012-05-29 10:48:58.326072 [(21)]: dvb:Submit tunerequest done calling put_TuneRequest
2012-05-29 10:48:58.326072 [(21)]: subch:0 OnAfterTune
2012-05-29 10:48:58.326072 [(21)]: RunGraph
2012-05-29 10:48:58.326072 [(21)]: subch:0 OnGraphStart
2012-05-29 10:48:58.326072 [(21)]: dvb:  RunGraph
2012-05-29 10:48:58.560072 [(21)]: dvb:  LockedInOnSignal ok
2012-05-29 10:48:58.560072 [(21)]: subch:0 OnGraphStarted
2012-05-29 10:48:58.560072 [(21)]: subch:0 SetupPmtGrabber:pid 65 sid:2EE3
2012-05-29 10:48:58.560072 [(21)]: subch:0 set pmt grabber pmt:65 sid:2EE3
2012-05-29 10:48:58.560072 [(21)]: WaitForPMT: Waiting for PMT 65
2012-05-29 10:48:58.840873 [(18)]: subch:0 OnPMTReceived() pmt:65 ran:True dynamic:False
2012-05-29 10:48:58.840873 [(21)]: WaitForPMT: Found PMT after 0,2808005 seconds.
2012-05-29 10:48:58.840873 [(21)]: subch:0 SendPmt:2EE3 2EE3 FFFFFFFF 1
2012-05-29 10:48:58.840873 [(21)]: Decode pmt
2012-05-29 10:48:58.840873 [(21)]: Set descriptor data with length 3
2012-05-29 10:48:58.840873 [(21)]: Set descriptor data with length 6
2012-05-29 10:48:58.840873 [(21)]: PROBLEM : descriptor lengths dont match 3 9
2012-05-29 10:48:58.840873 [(21)]: Set descriptor data with length 7
2012-05-29 10:48:58.840873 [(21)]: PROBLEM : descriptor lengths dont match 3 10
2012-05-29 10:48:58.840873 [(21)]: Set descriptor data with length 6
2012-05-29 10:48:58.840873 [(21)]: PROBLEM : descriptor lengths dont match 3 9
2012-05-29 10:48:58.840873 [(21)]: Set descriptor data with length 10
2012-05-29 10:48:58.840873 [(21)]: subch:0 SendPMT: Channel is FTA, nothing to do.
2012-05-29 10:48:58.856473 [(21)]: subch:0 SetMpegPidMapping
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:66 pcr
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:65 pmt
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:66 video type:MPEG-2
2012-05-29 10:48:58.856473 [(21)]: subch:0    map pid:66 video type:MPEG-2
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:67 audio lang:ger type:MPEG-1
2012-05-29 10:48:58.856473 [(21)]: subch:0    map pid:67 audio lang:ger type:MPEG-1
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:6C type:5
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:6A teletext type:6
2012-05-29 10:48:58.856473 [(21)]: subch:0    map pid:6A teletext type:6
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:69 audio lang:ger type:AC3
2012-05-29 10:48:58.856473 [(21)]: subch:0    map pid:69 audio lang:ger type:AC3
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:6E subtitle type:6
2012-05-29 10:48:58.856473 [(21)]: subch:0    map pid:6E subtitle type:6
2012-05-29 10:48:58.856473 [(21)]: subch:0  pid:6D type:86
2012-05-29 10:48:58.856473 [(21)]: subch:0 stop tif
2012-05-29 10:48:58.856473 [(21)]: WaitForPmt: PMT handling took 0,0156 seconds.
2012-05-29 10:48:58.856473 [(21)]: card: Tuner locked: True
2012-05-29 10:48:58.856473 [(21)]: **************************************************
2012-05-29 10:48:58.856473 [(21)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2012-05-29 10:48:58.856473 [(21)]: **************************************************
2012-05-29 10:48:58.856473 [(21)]: card: tuned user: epg subchannel: 0
2012-05-29 10:48:58.856473 [(21)]: user:epg add
2012-05-29 10:48:58.856473 [DVB EPG timer(4)]: Controller: GrabEpg on card ID == 1005
2012-05-29 10:48:58.856473 [DVB EPG timer(4)]: dvb:grab epg...
2012-05-29 10:58:48.053908 [(18)]: EPG: database updated for #0 RTL Television
2012-05-29 10:58:48.053908 [(18)]: user:epg remove
2012-05-29 10:58:48.069508 [(18)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2012-05-29 10:58:48.069508 [(18)]: DVB subch:0 Decompose()
2012-05-29 10:58:48.069508 [(18)]: FreeSubChannel CA: freeing sub channel : 0
2012-05-29 10:58:48.069508 [(18)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2012-05-29 10:58:48.069508 [(18)]: dvb:StopGraph called
2012-05-29 10:58:48.069508 [(18)]: tvcard:FreeAllSubChannels
2012-05-29 10:58:48.069508 [(18)]: dvb:StopGraph
2012-05-29 10:58:58.599526 [(19)]: card: Tune 1005 to Bayerisches FS Süd
2012-05-29 10:58:58.599526 [(19)]: card: user: epg:1005:-1 tune DVBC:tv:ARD Bayerisches FS Süd Freq:346000 ONID:41985 TSID:1101 SID:28107 PMT:0xC8 FTA:True LCN:667 SymbolRate:6900 Modulation:Mod256Qam
2012-05-29 10:58:58.615126 [(19)]: dvbc: Tune:DVBC:tv:ARD Bayerisches FS Süd Freq:346000 ONID:41985 TSID:1101 SID:28107 PMT:0xC8 FTA:True LCN:667 SymbolRate:6900 Modulation:Mod256Qam
2012-05-29 10:58:58.615126 [(19)]: dvb:Submiting tunerequest Channel:Bayerisches FS Süd subChannel:-1 
2012-05-29 10:58:58.615126 [(19)]: dvb:Getting new subchannel
2012-05-29 10:58:58.615126 [(19)]: dvb:GetNewSubChannel:0 #0
2012-05-29 19:47:39.492441 [(5)]: Controller: find free card for channel VOX
2012-05-29 19:47:39.508041 [(5)]: Controller: find card for channel VOX
2012-05-29 19:47:39.508041 [(5)]: Controller:  got 1 tuning details for VOX
2012-05-29 19:47:39.508041 [(5)]: Controller:  channel #1 DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam 
2012-05-29 19:47:39.508041 [(5)]: Controller:    card:1005 type:DvbC can tune to channel
2012-05-29 19:47:39.508041 [(5)]: Controller:    card:1005 type:DvbC users: 0
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1006 type:DvbC can tune to channel
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1006 type:DvbC users: 0
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:2 type:RadioWebStream is disabled
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1007 type:DvbT is disabled
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1008 type:DvbT is disabled
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1009 type:DvbS is disabled
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1010 type:DvbS is disabled
2012-05-29 19:47:39.523641 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 19:47:39.523641 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 15 msec
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1005 type:DvbC is available
2012-05-29 19:47:39.523641 [(5)]: Controller:    card:1006 type:DvbC is available
2012-05-29 19:47:39.523641 [(5)]: Controller: found 2 free card(s)
2012-05-29 19:47:39.523641 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 25 msec
2012-05-29 19:47:39.523641 [(5)]: Controller: TimeShiftingWouldUseCard VOX 653
2012-05-29 19:47:39.523641 [(5)]: Controller: find free card for channel VOX
2012-05-29 19:47:39.523641 [(5)]: Controller: find card for channel VOX
2012-05-29 19:47:39.539241 [(5)]: Controller:  got 1 tuning details for VOX
2012-05-29 19:47:39.539241 [(5)]: Controller:  channel #1 DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam 
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1005 type:DvbC can tune to channel
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1005 type:DvbC users: 0
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1006 type:DvbC can tune to channel
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1006 type:DvbC users: 0
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:2 type:RadioWebStream is disabled
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1007 type:DvbT is disabled
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1008 type:DvbT is disabled
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1009 type:DvbS is disabled
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1010 type:DvbS is disabled
2012-05-29 19:47:39.539241 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 19:47:39.539241 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 6 msec
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1005 type:DvbC is available
2012-05-29 19:47:39.539241 [(5)]: Controller:    card:1006 type:DvbC is available
2012-05-29 19:47:39.539241 [(5)]: Controller: found 2 free card(s)
2012-05-29 19:47:39.539241 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 7 msec
2012-05-29 19:47:39.539241 [(5)]: CiMenuSupported called cardid 1005
2012-05-29 19:47:39.539241 [(5)]: CiMenuSupported card Digital Devices DVB-C Tuner 1 supported: False
2012-05-29 19:47:39.554841 [(5)]: Controller: StartTimeShifting VOX 653
2012-05-29 19:47:39.554841 [(5)]: user:epg remove
2012-05-29 19:47:39.554841 [(5)]: Controller: find free card for channel VOX
2012-05-29 19:47:39.554841 [(5)]: Controller: find card for channel VOX
2012-05-29 19:47:39.554841 [(5)]: Controller:  got 1 tuning details for VOX
2012-05-29 19:47:39.570441 [(5)]: Controller:  channel #1 DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam 
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1005 type:DvbC can tune to channel
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1005 type:DvbC users: 0
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1006 type:DvbC can tune to channel
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1006 type:DvbC users: 0
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:2 type:RadioWebStream is disabled
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1007 type:DvbT is disabled
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1008 type:DvbT is disabled
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1009 type:DvbS is disabled
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1010 type:DvbS is disabled
2012-05-29 19:47:39.570441 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 19:47:39.570441 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 7 msec
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1005 type:DvbC is available
2012-05-29 19:47:39.570441 [(5)]: Controller:    card:1006 type:DvbC is available
2012-05-29 19:47:39.570441 [(5)]: Controller: found 2 free card(s)
2012-05-29 19:47:39.570441 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 8 msec
2012-05-29 19:47:39.570441 [(5)]: Controller: try max 2 of 2 cards for timeshifting
2012-05-29 19:47:39.570441 [(5)]: card: CardTune 1005 VOX Wohn-Box:1005:-1
2012-05-29 19:47:39.586041 [(5)]: card: Tune 1005 to VOX
2012-05-29 19:47:39.586041 [(5)]: card: user: Wohn-Box:1005:-1 tune DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-05-29 19:47:39.586041 [(5)]: TimeShifter.OnBeforeTune: resetting audio/video events
2012-05-29 19:47:39.586041 [(5)]: dvbc: Tune:DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-05-29 19:47:39.586041 [(5)]: dvb:Submiting tunerequest Channel:VOX subChannel:-1 
2012-05-29 19:47:39.586041 [(5)]: dvb:Getting new subchannel
2012-05-29 19:47:39.586041 [(5)]: dvb:GetNewSubChannel:0 #1

EPG_Log_2 (EPG until 00:58, TV from 19:10)
Code:
2012-06-06 00:52:37.240594 [DVB EPG timer(4)]: Grab for card:#1005 transponder #1/5 channel: RTL Television
2012-06-06 00:52:37.240594 [DVB EPG timer(4)]: EpgCard: grab epg on card: #1005 transponder: #0 ch:RTL Television
2012-06-06 00:52:37.880195 [DVB EPG timer(4)]: EpgGrabbing: Start
2012-06-06 00:52:37.895795 [DVB EPG timer(4)]: EpgCard: card: 1005 starting to grab DVBC:tv:RTL RTL Television Freq:113000 ONID:40984 TSID:1089 SID:12003 PMT:0x65 FTA:True LCN:650 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 00:53:07.925848 [(4)]: EpgCard: allow grabbing for 30,0300527 seconds on card 1005
2012-06-06 00:53:37.940301 [(12)]: EpgCard: allow grabbing for 60,0445054 seconds on card 1005
2012-06-06 00:54:07.954754 [(12)]: EpgCard: allow grabbing for 90,0589582 seconds on card 1005
2012-06-06 00:54:37.969206 [(12)]: EpgCard: allow grabbing for 120,0734109 seconds on card 1005
2012-06-06 00:55:07.999259 [(12)]: EpgCard: allow grabbing for 150,1034636 seconds on card 1005
2012-06-06 00:55:37.998112 [(4)]: EpgCard: allow grabbing for 180,1023163 seconds on card 1005
2012-06-06 00:56:08.012564 [(4)]: EpgCard: allow grabbing for 210,116769 seconds on card 1005
2012-06-06 00:56:38.027017 [(12)]: EpgCard: allow grabbing for 240,1312217 seconds on card 1005
2012-06-06 00:57:08.041470 [(12)]: EpgCard: allow grabbing for 270,1456745 seconds on card 1005
2012-06-06 00:57:38.071523 [(12)]: EpgCard: allow grabbing for 300,1601272 seconds on card 1005
2012-06-06 00:58:04.185969 [(16)]: dvb:mhw ready MHW 0 titles found
2012-06-06 00:58:04.185969 [(16)]: dvb:dvb ready.EPG 0 channels
2012-06-06 00:58:04.185969 [(16)]: Epg: card:1005 no epg found
2012-06-06 00:58:04.185969 [(16)]: EpgGrabbing: Stop - user epg
2012-06-06 00:58:07.430774 [DVB EPG timer(4)]: Grab for card:#1005 transponder #2/5 channel: SAT 1
2012-06-06 00:58:07.430774 [DVB EPG timer(4)]: EpgCard: grab epg on card: #1005 transponder: #1 ch:SAT 1
2012-06-06 19:10:56.077539 [(17)]: EPG: grabber stopped..
2012-06-06 19:10:56.077539 [(17)]: EpgCard: card: 1005 stop grabbing
2012-06-06 19:10:56.077539 [(17)]: EpgGrabbing: Stop - user epg

TV_Log_2 (EPG until 00:58, TV from 19:10)
Code:
2012-06-06 00:52:37.256194 [(8)]: card: Tune 1005 to RTL Television
2012-06-06 00:52:37.256194 [(8)]: card: user: epg:1005:-1 tune DVBC:tv:RTL RTL Television Freq:113000 ONID:40984 TSID:1089 SID:12003 PMT:0x65 FTA:True LCN:650 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 00:52:37.256194 [(8)]: TimeShifter.OnBeforeTune: resetting audio/video events
2012-06-06 00:52:37.271794 [(8)]: dvbc: Tune:DVBC:tv:RTL RTL Television Freq:113000 ONID:40984 TSID:1089 SID:12003 PMT:0x65 FTA:True LCN:650 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 00:52:37.271794 [(8)]: dvb:Submiting tunerequest Channel:RTL Television subChannel:-1
2012-06-06 00:52:37.271794 [(8)]: dvb:Getting new subchannel
2012-06-06 00:52:37.271794 [(8)]: dvb:GetNewSubChannel:0 #0
2012-06-06 00:52:37.271794 [(8)]: TvDvbChannel ctor new subchIndex:0
2012-06-06 00:52:37.271794 [(8)]: dvb:Submit tunerequest size:1 new:0
2012-06-06 00:52:37.271794 [(8)]: subch:0 OnBeforeTune
2012-06-06 00:52:37.271794 [(8)]: dvb:Submit tunerequest calling put_TuneRequest
2012-06-06 00:52:37.271794 [(8)]: dvb:Submit tunerequest done calling put_TuneRequest
2012-06-06 00:52:37.271794 [(8)]: subch:0 OnAfterTune
2012-06-06 00:52:37.271794 [(8)]: RunGraph
2012-06-06 00:52:37.271794 [(8)]: subch:0 OnGraphStart
2012-06-06 00:52:37.271794 [(8)]: dvb:  RunGraph
2012-06-06 00:52:37.536995 [(8)]: dvb:  LockedInOnSignal ok
2012-06-06 00:52:37.536995 [(8)]: subch:0 OnGraphStarted
2012-06-06 00:52:37.536995 [(8)]: subch:0 SetupPmtGrabber:pid 65 sid:2EE3
2012-06-06 00:52:37.536995 [(8)]: subch:0 set pmt grabber pmt:65 sid:2EE3
2012-06-06 00:52:37.536995 [(8)]: TimeShifter.OnAfterTune: resetting audio/video time
2012-06-06 00:52:37.536995 [(8)]: WaitForPMT: Waiting for PMT 65
2012-06-06 00:52:37.864595 [(16)]: subch:0 OnPMTReceived() pmt:65 ran:True dynamic:False
2012-06-06 00:52:37.864595 [(8)]: WaitForPMT: Found PMT after 0,3276006 seconds.
2012-06-06 00:52:37.864595 [(8)]: subch:0 SendPmt:2EE3 2EE3 FFFFFFFF 1
2012-06-06 00:52:37.864595 [(8)]: Decode pmt
2012-06-06 00:52:37.864595 [(8)]: Set descriptor data with length 3
2012-06-06 00:52:37.864595 [(8)]: Set descriptor data with length 6
2012-06-06 00:52:37.864595 [(8)]: PROBLEM : descriptor lengths dont match 3 9
2012-06-06 00:52:37.864595 [(8)]: Set descriptor data with length 7
2012-06-06 00:52:37.864595 [(8)]: PROBLEM : descriptor lengths dont match 3 10
2012-06-06 00:52:37.864595 [(8)]: Set descriptor data with length 6
2012-06-06 00:52:37.880195 [(8)]: PROBLEM : descriptor lengths dont match 3 9
2012-06-06 00:52:37.880195 [(8)]: Set descriptor data with length 10
2012-06-06 00:52:37.880195 [(8)]: subch:0 SendPMT: Channel is FTA, nothing to do.
2012-06-06 00:52:37.880195 [(8)]: subch:0 SetMpegPidMapping
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:66 pcr
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:65 pmt
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:66 video type:MPEG-2
2012-06-06 00:52:37.880195 [(8)]: subch:0    map pid:66 video type:MPEG-2
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:67 audio lang:ger type:MPEG-1
2012-06-06 00:52:37.880195 [(8)]: subch:0    map pid:67 audio lang:ger type:MPEG-1
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:6C type:5
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:6A teletext type:6
2012-06-06 00:52:37.880195 [(8)]: subch:0    map pid:6A teletext type:6
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:69 audio lang:ger type:AC3
2012-06-06 00:52:37.880195 [(8)]: subch:0    map pid:69 audio lang:ger type:AC3
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:6E subtitle type:6
2012-06-06 00:52:37.880195 [(8)]: subch:0    map pid:6E subtitle type:6
2012-06-06 00:52:37.880195 [(8)]: subch:0  pid:6D type:86
2012-06-06 00:52:37.880195 [(8)]: subch:0 stop tif
2012-06-06 00:52:37.880195 [(8)]: WaitForPmt: PMT handling took 0,0156001 seconds.
2012-06-06 00:52:37.880195 [(8)]: card: Tuner locked: True
2012-06-06 00:52:37.880195 [(8)]: **************************************************
2012-06-06 00:52:37.880195 [(8)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2012-06-06 00:52:37.880195 [(8)]: **************************************************
2012-06-06 00:52:37.880195 [(8)]: card: tuned user: epg subchannel: 0
2012-06-06 00:52:37.880195 [(8)]: user:epg add
2012-06-06 00:52:37.880195 [DVB EPG timer(4)]: Controller: GrabEpg on card ID == 1005
2012-06-06 00:52:37.880195 [DVB EPG timer(4)]: dvb:grab epg...
2012-06-06 00:58:04.185969 [(16)]: EPG: database updated for #0 RTL Television
2012-06-06 00:58:04.185969 [(16)]: user:epg remove
2012-06-06 00:58:04.185969 [(16)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2012-06-06 00:58:04.201569 [(16)]: DVB subch:0 Decompose()
2012-06-06 00:58:04.201569 [(16)]: FreeSubChannel CA: freeing sub channel : 0
2012-06-06 00:58:04.201569 [(16)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2012-06-06 00:58:04.201569 [(16)]: dvb:StopGraph called
2012-06-06 00:58:04.201569 [(16)]: tvcard:FreeAllSubChannels
2012-06-06 00:58:04.201569 [(16)]: dvb:StopGraph
2012-06-06 00:58:07.430774 [(24)]: card: Tune 1005 to SAT 1
2012-06-06 00:58:07.430774 [(24)]: card: user: epg:1005:-1 tune DVBC:tv:Pro7 & Sat.1 SAT 1 Freq:121000 ONID:40984 TSID:1107 SID:17500 PMT:0xC9 FTA:True LCN:657 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 00:58:07.446374 [(24)]: TimeShifter.OnBeforeTune: resetting audio/video events
2012-06-06 00:58:07.446374 [(24)]: dvbc: Tune:DVBC:tv:Pro7 & Sat.1 SAT 1 Freq:121000 ONID:40984 TSID:1107 SID:17500 PMT:0xC9 FTA:True LCN:657 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 00:58:07.446374 [(24)]: dvb:Submiting tunerequest Channel:SAT 1 subChannel:-1
2012-06-06 00:58:07.446374 [(24)]: dvb:Getting new subchannel
2012-06-06 00:58:07.446374 [(24)]: dvb:GetNewSubChannel:0 #0
2012-06-06 19:10:56.015139 [(17)]: Controller: find free card for channel VOX
2012-06-06 19:10:56.015139 [(17)]: Controller: find card for channel VOX
2012-06-06 19:10:56.015139 [(17)]: Controller:  got 1 tuning details for VOX
2012-06-06 19:10:56.030739 [(17)]: Controller:  channel #1 DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1005 type:DvbC can tune to channel
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1005 type:DvbC users: 0
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1006 type:DvbC can tune to channel
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1006 type:DvbC users: 0
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:2 type:RadioWebStream is disabled
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1007 type:DvbT is disabled
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1008 type:DvbT is disabled
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1009 type:DvbS is disabled
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1010 type:DvbS is disabled
2012-06-06 19:10:56.030739 [(17)]: Controller: found 2 card(s) for channel
2012-06-06 19:10:56.030739 [(17)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 13 msec
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1005 type:DvbC is available
2012-06-06 19:10:56.030739 [(17)]: Controller:    card:1006 type:DvbC is available
2012-06-06 19:10:56.030739 [(17)]: Controller: found 2 free card(s)
2012-06-06 19:10:56.030739 [(17)]: AdvancedCardAllocation.GetFreeCardsForChannel took 17 msec
2012-06-06 19:10:56.046339 [(17)]: Controller: TimeShiftingWouldUseCard VOX 653
2012-06-06 19:10:56.046339 [(17)]: Controller: find free card for channel VOX
2012-06-06 19:10:56.046339 [(17)]: Controller: find card for channel VOX
2012-06-06 19:10:56.046339 [(17)]: Controller:  got 1 tuning details for VOX
2012-06-06 19:10:56.046339 [(17)]: Controller:  channel #1 DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1005 type:DvbC can tune to channel
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1005 type:DvbC users: 0
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1006 type:DvbC can tune to channel
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1006 type:DvbC users: 0
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:2 type:RadioWebStream is disabled
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1007 type:DvbT is disabled
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1008 type:DvbT is disabled
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1009 type:DvbS is disabled
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1010 type:DvbS is disabled
2012-06-06 19:10:56.046339 [(17)]: Controller: found 2 card(s) for channel
2012-06-06 19:10:56.046339 [(17)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 6 msec
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1005 type:DvbC is available
2012-06-06 19:10:56.046339 [(17)]: Controller:    card:1006 type:DvbC is available
2012-06-06 19:10:56.046339 [(17)]: Controller: found 2 free card(s)
2012-06-06 19:10:56.046339 [(17)]: AdvancedCardAllocation.GetFreeCardsForChannel took 8 msec
2012-06-06 19:10:56.061939 [(17)]: CiMenuSupported called cardid 1005
2012-06-06 19:10:56.061939 [(17)]: CiMenuSupported card Digital Devices DVB-C Tuner 1 supported: False
2012-06-06 19:10:56.077539 [(17)]: Controller: StartTimeShifting VOX 653
2012-06-06 19:10:56.077539 [(17)]: user:epg remove
2012-06-06 19:10:56.093139 [(17)]: Controller: find free card for channel VOX
2012-06-06 19:10:56.093139 [(17)]: Controller: find card for channel VOX
2012-06-06 19:10:56.093139 [(17)]: Controller:  got 1 tuning details for VOX
2012-06-06 19:10:56.093139 [(17)]: Controller:  channel #1 DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1005 type:DvbC can tune to channel
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1005 type:DvbC users: 0
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1006 type:DvbC can tune to channel
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1006 type:DvbC users: 0
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:2 type:RadioWebStream is disabled
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1007 type:DvbT is disabled
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1008 type:DvbT is disabled
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1009 type:DvbS is disabled
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1010 type:DvbS is disabled
2012-06-06 19:10:56.093139 [(17)]: Controller: found 2 card(s) for channel
2012-06-06 19:10:56.093139 [(17)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 7 msec
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1005 type:DvbC is available
2012-06-06 19:10:56.093139 [(17)]: Controller:    card:1006 type:DvbC is available
2012-06-06 19:10:56.093139 [(17)]: Controller: found 2 free card(s)
2012-06-06 19:10:56.093139 [(17)]: AdvancedCardAllocation.GetFreeCardsForChannel took 9 msec
2012-06-06 19:10:56.093139 [(17)]: Controller: try max 2 of 2 cards for timeshifting
2012-06-06 19:10:56.093139 [(17)]: card: CardTune 1005 VOX Wohn-Box:1005:-1
2012-06-06 19:10:56.093139 [(17)]: card: Tune 1005 to VOX
2012-06-06 19:10:56.093139 [(17)]: card: user: Wohn-Box:1005:-1 tune DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 19:10:56.108740 [(17)]: TimeShifter.OnBeforeTune: resetting audio/video events
2012-06-06 19:10:56.108740 [(17)]: dvbc: Tune:DVBC:tv:RTL VOX Freq:113000 ONID:40984 TSID:1089 SID:12060 PMT:0x191 FTA:True LCN:653 SymbolRate:6900 Modulation:Mod64Qam
2012-06-06 19:10:56.108740 [(17)]: dvb:Submiting tunerequest Channel:VOX subChannel:-1
2012-06-06 19:10:56.108740 [(17)]: dvb:Getting new subchannel
2012-06-06 19:10:56.108740 [(17)]: dvb:GetNewSubChannel:0 #1

TSWriter_Log_2 (EPG until 00:58, TV from 19:10)
Code:
06-06-2012 00:52:37.271 analyzer: reset
06-06-2012 00:52:37.271 PatParser:Reset(0)
06-06-2012 00:52:37.271 NIT:Reset
06-06-2012 00:52:37.271 PatParser::Reset done
06-06-2012 00:52:37.271 cagrabber: reset
06-06-2012 00:52:37.271 epg: reset
06-06-2012 00:52:37.271 epg:ResetEPG()
06-06-2012 00:52:37.271 mhw reset
06-06-2012 00:52:37.271 CMpTsFilter::Pause()
06-06-2012 00:52:37.271 CMpTsFilter::Run()
06-06-2012 00:52:37.536 pmtgrabber: set callback:52feb8
06-06-2012 00:52:37.536 pmtgrabber: grab pmt:65 sid:2ee3
06-06-2012 00:52:37.864 pmtgrabber: got pmt 65 sid:2ee3
06-06-2012 00:52:37.864 pmtgrabber: PMT pids changed from:
06-06-2012 00:52:37.864  pcr      pid:    0 
06-06-2012 00:52:37.864  pmt      pid:    0 
06-06-2012 00:52:37.864 pmtgrabber: PMT pids changed to:
06-06-2012 00:52:37.864  pcr      pid:  66 
06-06-2012 00:52:37.864  pmt      pid:  65 
06-06-2012 00:52:37.864  video    pid:  66 type: MPEG2
06-06-2012 00:52:37.864  audio    pid:  67 language: ger type: MPEG1 - audio
06-06-2012 00:52:37.864  audio    pid:  69 language: ger type: AC3
06-06-2012 00:52:37.864  Subtitle pid:  6e language: deu type: DVB subtitle 2
06-06-2012 00:52:37.864 pmtgrabber: got new pmt version:1 ffffffff, service_id:2ee3
06-06-2012 00:52:37.864 pmtgrabber: do callback pid 65
06-06-2012 00:52:37.880 analyzer: set video pid:66
06-06-2012 00:52:37.880 analyzer: reset
06-06-2012 00:52:37.880 analyzer: set audio pid:67
06-06-2012 00:52:37.880 analyzer: reset
06-06-2012 00:52:37.880 analyzer: set audio pid:69
06-06-2012 00:52:37.880 analyzer: reset
06-06-2012 00:52:37.880 epg: set callback
06-06-2012 00:52:37.880 EpgScanner::GrabEPG
06-06-2012 00:52:37.880 epg:GrabEPG
06-06-2012 00:52:37.880 epg:ResetEPG()
06-06-2012 00:52:37.880 epg:GrabEPG()
06-06-2012 00:52:37.880 EpgScanner::GrabMHW
06-06-2012 00:52:37.880 mhw grab
06-06-2012 00:52:37.880 mhw reset
06-06-2012 00:52:37.880 mhw grabber started decoders:2
06-06-2012 00:58:04.170 mhw grabber ended
06-06-2012 00:58:04.170 epg: epg received
06-06-2012 00:58:04.170 epg: do callback
06-06-2012 00:58:04.185 mhw grabber ended
06-06-2012 00:58:04.185 CMhwParser:GetTitleCount:0
06-06-2012 00:58:04.185 EpgScanner::GetMHWTitleCount:0
06-06-2012 00:58:04.185 epg: reset
06-06-2012 00:58:04.185 epg:ResetEPG()
06-06-2012 00:58:04.185 mhw reset
06-06-2012 00:58:04.201 del m_pVideoAnalyzer
06-06-2012 00:58:04.201 analyzer: reset
06-06-2012 00:58:04.201 del m_pPmtGrabber
06-06-2012 00:58:04.201 del m_pRecorder
06-06-2012 00:58:04.201 del m_pTimeShifting
06-06-2012 00:58:04.201 del m_pTeletextGrabber
06-06-2012 00:58:04.201 del m_pCaGrabber
06-06-2012 00:58:04.201 del done...
06-06-2012 00:58:04.201 CMpTsFilter::Pause()
06-06-2012 00:58:04.201 CMpTsFilter::Stop()
06-06-2012 19:18:02.805 CMpTs::ctor()
06-06-2012 19:18:02.805 --------------- BUG-3782 fix v2 -------------------
06-06-2012 19:18:02.805 CMpTsFilterPin:ctor
06-06-2012 19:18:02.836 PatParser:Reset(0)
06-06-2012 19:18:02.836 NIT:Reset
06-06-2012 19:18:02.836 PatParser::Reset done
06-06-2012 19:18:02.836 epg:ResetEPG()
06-06-2012 19:18:02.836 mhw ctor
06-06-2012 19:18:04.693 analyzer: reset
06-06-2012 19:18:04.693 PatParser:Reset(0)
06-06-2012 19:18:04.693 NIT:Reset
06-06-2012 19:18:04.693 PatParser::Reset done
06-06-2012 19:18:04.693 cagrabber: reset
06-06-2012 19:18:04.708 epg: reset
06-06-2012 19:18:04.708 epg:ResetEPG()
06-06-2012 19:18:04.708 mhw reset
06-06-2012 19:18:04.833 CMpTsFilter::Pause()
06-06-2012 19:18:04.849 CMpTsFilter::Run()
06-06-2012 19:18:05.83 pmtgrabber: set callback:4f0038
06-06-2012 19:18:05.83 pmtgrabber: grab pmt:c9 sid:445c
06-06-2012 19:18:05.270 pmtgrabber: got pmt c9 sid:445c
06-06-2012 19:18:05.270 pmtgrabber: PMT pids changed from:
06-06-2012 19:18:05.270  pcr      pid:    0 
06-06-2012 19:18:05.270  pmt      pid:    0 
06-06-2012 19:18:05.270 pmtgrabber: PMT pids changed to:
06-06-2012 19:18:05.270  pcr      pid:  ca 
06-06-2012 19:18:05.270  pmt      pid:  c9 
06-06-2012 19:18:05.270  video    pid:  ca type: MPEG2
06-06-2012 19:18:05.270  audio    pid:  cb language: deu type: MPEG1 - audio
06-06-2012 19:18:05.270  audio    pid:  cd language: deu type: AC3
06-06-2012 19:18:05.270 pmtgrabber: got new pmt version:1 ffffffff, service_id:445c
06-06-2012 19:18:05.270 pmtgrabber: do callback pid c9
06-06-2012 19:18:05.301 analyzer: set video pid:ca
06-06-2012 19:18:05.301 analyzer: reset
06-06-2012 19:18:05.301 analyzer: set audio pid:cb
06-06-2012 19:18:05.301 analyzer: reset
06-06-2012 19:18:05.301 analyzer: set audio pid:cd
06-06-2012 19:18:05.301 analyzer: reset
06-06-2012 19:18:05.442 epg: set callback
06-06-2012 19:18:05.442 EpgScanner::GrabEPG
06-06-2012 19:18:05.442 epg:GrabEPG
06-06-2012 19:18:05.442 epg:ResetEPG()
06-06-2012 19:18:05.442 epg:GrabEPG()
06-06-2012 19:18:05.442 EpgScanner::GrabMHW
06-06-2012 19:18:05.442 mhw grab
06-06-2012 19:18:05.442 mhw reset
06-06-2012 19:18:05.442 mhw grabber started decoders:2
06-06-2012 19:19:16.32 mhw grabber ended
06-06-2012 19:19:16.32 epg: epg received
06-06-2012 19:19:16.32 epg: do callback
06-06-2012 19:19:16.47 mhw grabber ended
06-06-2012 19:19:16.47 CMhwParser:GetTitleCount:0
06-06-2012 19:19:16.47 EpgScanner::GetMHWTitleCount:0
06-06-2012 19:19:16.110 epg: reset
06-06-2012 19:19:16.110 epg:ResetEPG()
06-06-2012 19:19:16.110 mhw reset
06-06-2012 19:19:17.295 del m_pVideoAnalyzer
06-06-2012 19:19:17.295 analyzer: reset
06-06-2012 19:19:17.295 del m_pPmtGrabber
06-06-2012 19:19:17.295 del m_pRecorder
06-06-2012 19:19:17.295 del m_pTimeShifting
06-06-2012 19:19:17.295 del m_pTeletextGrabber
06-06-2012 19:19:17.295 del m_pCaGrabber
06-06-2012 19:19:17.295 del done...
06-06-2012 19:19:17.295 CMpTsFilter::Pause()
06-06-2012 19:19:17.295 CMpTsFilter::Stop()
06-06-2012 19:19:32.412 analyzer: reset
06-06-2012 19:19:32.412 PatParser:Reset(0)
06-06-2012 19:19:32.412 NIT:Reset
06-06-2012 19:19:32.412 PatParser::Reset done
06-06-2012 19:19:32.412 cagrabber: reset
06-06-2012 19:19:32.412 epg: reset
06-06-2012 19:19:32.412 epg:ResetEPG()
06-06-2012 19:19:32.412 mhw reset
06-06-2012 19:19:32.443 CMpTsFilter::Pause()
06-06-2012 19:19:32.443 CMpTsFilter::Run()
06-06-2012 19:19:32.677 pmtgrabber: set callback:4fffd8
06-06-2012 19:19:32.677 pmtgrabber: grab pmt:1770 sid:2b5c
06-06-2012 19:19:33.67 pmtgrabber: got pmt 1770 sid:2b5c
06-06-2012 19:19:33.67 pmtgrabber: PMT pids changed from:
06-06-2012 19:19:33.67  pcr      pid:    0 
06-06-2012 19:19:33.67  pmt      pid:    0 
06-06-2012 19:19:33.67 pmtgrabber: PMT pids changed to:
06-06-2012 19:19:33.67  pcr      pid: 177a 
06-06-2012 19:19:33.67  pmt      pid: 1770 
06-06-2012 19:19:33.67  video    pid: 177a type: H264
06-06-2012 19:19:33.67  audio    pid: 1784 language: deu type: MPEG1 - audio
06-06-2012 19:19:33.67  audio    pid: 1785 language: mis type: MPEG1 - audio
06-06-2012 19:19:33.67  audio    pid: 1786 language: deu type: AC3
06-06-2012 19:19:33.67  Subtitle pid: 178f language: deu type: DVB subtitle 2
06-06-2012 19:19:33.67 pmtgrabber: got new pmt version:1 ffffffff, service_id:2b5c
06-06-2012 19:19:33.67 pmtgrabber: do callback pid 1770
06-06-2012 19:19:33.83 analyzer: set video pid:177a
06-06-2012 19:19:33.83 analyzer: reset
06-06-2012 19:19:33.83 analyzer: set audio pid:1784
06-06-2012 19:19:33.83 analyzer: reset
06-06-2012 19:19:33.83 analyzer: set audio pid:1786
06-06-2012 19:19:33.83 analyzer: reset
06-06-2012 19:19:33.83 epg: set callback
06-06-2012 19:19:33.83 EpgScanner::GrabEPG
06-06-2012 19:19:33.83 epg:GrabEPG
06-06-2012 19:19:33.83 epg:ResetEPG()
06-06-2012 19:19:33.83 epg:GrabEPG()
06-06-2012 19:19:33.83 EpgScanner::GrabMHW
06-06-2012 19:19:33.83 mhw grab
06-06-2012 19:19:33.83 mhw reset
06-06-2012 19:19:33.83 mhw grabber started decoders:2
06-06-2012 19:20:19.929 del m_pVideoAnalyzer

EPG_Log_3 (EPG until 04:27, start TV at 10:58)
Please see attached file epg_20120607.log

TV_Log_3 (EPG until 04:27, start TV at 10:58)
Please see attached file TV_20120607.log

TSWriter:
Please see attached file: TsWriter-2012-06-07.Log
 

robbo100

Retired Team Member
  • Premium Supporter
  • May 5, 2009
    1,291
    308
    UK
    Home Country
    United Kingdom United Kingdom
    Try disabling grabbing whilst at idle (just keep the option for grabbing whilst timeshifting/recording ticked)
     

    Raptor75

    Portal Member
    October 5, 2012
    16
    3
    Home Country
    Malta Malta
    Hi Razorblade. Did this work out for you? I've had to disable EPG completely due to this issue but I haven't tried just leaving the grabbing while timeshifting.

    Maybe I'll try it this way.
     

    Razorblade

    Portal Pro
    May 22, 2010
    99
    3
    Home Country
    Germany Germany
    Hi Raptor,

    yes that did help indeed.
    In my case I just had to disable "grabbing while idle" and replace it with "grabbing while timeshifting".
    Since then it's okay. Not perfect because it only grabs that special transport but that better than nothing :)

    I am waiting for a bug fix, maybe in 1.3.0 or 2.0
     

    Raptor75

    Portal Member
    October 5, 2012
    16
    3
    Home Country
    Malta Malta
    Cheers. I've enabled it too without issues. As you say better than nothing.

    However I've also configured WebEPG and XMLTV to make up for the lack of info. Doesn't work for everything but again, better than nothing. :)
     

    TheEngineer0815

    Portal Member
    December 18, 2011
    36
    4
    Kärnten
    Home Country
    Austria Austria
    Hallo!

    I have the same Problem - Idle Grabbing kills the TV Server.
    Because i dont wacht everyday this is anoying - my EPG is very often very empty......

    Hopefully this will be fixed
     

    DJBlu

    Portal Pro
    August 14, 2007
    1,670
    813
    Llanelli
    Home Country
    United Kingdom United Kingdom
    I am currently working on the fix for this issue.

    Can you try this config for me,

    Select grab whilst idle. Make sure grab for only select channels is de-selected.

    Then let me know how you get on.

    Thanks.
     

    TheEngineer0815

    Portal Member
    December 18, 2011
    36
    4
    Kärnten
    Home Country
    Austria Austria
    Hallo, sry I cant Test it...

    I have changed my HTPC against a Twin Sat Receiver due to Hardwaretroubles......
     

    SciDoctor

    Retired Team Member
  • Premium Supporter
  • February 2, 2005
    1,465
    139
    England
    @DJBlu .I can do some testing for this if you need logs etc.

    If you are looking into the DVB EPG grab I have two serious issues one which is critical.

    Critical. Concurrent DB updates cause crash (multiple timeshift EPG) ; I believe checks on the database write completion are not being actioned to increase DB speed, the slower method with full checking for write completion is required.

    Serious. When IDLE EPG starts, a check in the DB 'last grab time' dbo.Channel and the channels that require update are loaded and a grab is taken from each channel in turn until finished.
    What is missing from the logic is to recheck dbo.Channel after each channel grab for the now newly updated DB for 'last grab time' and to reload the new channels that now require update if any. (also those with gaps; epgHasGaps)
    For UK DVB the way the logic is at the momnet idle epg grab cycle will take over 700 minutes to complete (over 100 channels, 7 mins a channel grab/DB update) because this simple recheck isn't in the logic flow.
    I know I can deselect channels for grab to reduce this work load but this will now impact Timeshift EPG for those channels, a change in the logic would correct for this and allow a concise and accurate grab for all channels with respect to 'epgHasGaps' and 'last grab time'
     

    Users who are viewing this thread

    Top Bottom