2013-03-25 16:45:59.381088 [(19)]: Controller: StartTimeShifting Das Erste 21
2013-03-25 16:45:59.382088 [(19)]: Controller: find free card for channel Das Erste
2013-03-25 16:45:59.384088 [(19)]: Controller: find card for channel Das Erste
2013-03-25 16:45:59.390089 [(19)]: Controller:   got 1 tuning details for Das Erste
2013-03-25 16:45:59.391089 [(19)]: Controller:   channel #1 DVBC:tv:ARD Das Erste Freq:338000 ONID:1 TSID:1101 SID:28106 PMT:0x64 FTA:True LCN:21 SymbolRate:6900 Modulation:Mod256Qam 
2013-03-25 16:45:59.393089 [(19)]: Controller:    card:1 type:DvbIP cannot tune to channel
2013-03-25 16:45:59.396089 [(19)]: Controller:    card:2 type:DvbC can tune to channel
2013-03-25 16:45:59.397089 [(19)]: Controller:    card:2 type:DvbC users: 0
2013-03-25 16:45:59.398089 [(19)]: Controller:    card:3 type:DvbC is disabled
2013-03-25 16:45:59.399089 [(19)]: Controller:    card:6 type:DvbT is disabled
2013-03-25 16:45:59.399089 [(19)]: Controller:    card:7 type:DvbT is disabled
2013-03-25 16:45:59.400089 [(19)]: Controller:    card:10 type:DvbS is disabled
2013-03-25 16:45:59.401089 [(19)]: Controller:    card:11 type:DvbS is disabled
2013-03-25 16:45:59.402090 [(19)]: Controller:    card:14 type:RadioWebStream cannot tune to channel
2013-03-25 16:45:59.403090 [(19)]: Controller:    card:15 type:DvbC is disabled
2013-03-25 16:45:59.403090 [(19)]: Controller:    card:16 type:DvbC is disabled
2013-03-25 16:45:59.405090 [(19)]: Controller:    card:17 type:DvbT is disabled
2013-03-25 16:45:59.406090 [(19)]: Controller:    card:18 type:DvbT is disabled
2013-03-25 16:45:59.407090 [(19)]: Controller:    card:19 type:DvbS is disabled
2013-03-25 16:45:59.408090 [(19)]: Controller:    card:20 type:DvbS is disabled
2013-03-25 16:45:59.408090 [(19)]: Controller: found 1 card(s) for channel
2013-03-25 16:45:59.409090 [(19)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 25 msec
2013-03-25 16:45:59.410090 [(19)]: Controller:    card:2 type:DvbC is available
2013-03-25 16:45:59.411090 [(19)]: Controller: found 1 free card(s)
2013-03-25 16:45:59.411090 [(19)]: AdvancedCardAllocation.GetFreeCardsForChannel took 29 msec
2013-03-25 16:45:59.412090 [(19)]: Controller: try max 1 of 1 cards for timeshifting
2013-03-25 16:45:59.418090 [(19)]: card: CardTune 2 Das Erste setuptv:2:-1
2013-03-25 16:45:59.419090 [(19)]: card: Tune 2 to Das Erste
2013-03-25 16:45:59.420091 [(19)]: card: user: setuptv:2:-1 tune DVBC:tv:ARD Das Erste Freq:338000 ONID:1 TSID:1101 SID:28106 PMT:0x64 FTA:True LCN:21 SymbolRate:6900 Modulation:Mod256Qam
2013-03-25 16:45:59.435091 [(19)]: TimeShifter.OnBeforeTune: resetting audio/video events
2013-03-25 16:45:59.436091 [(19)]: dvbc: Tune:DVBC:tv:ARD Das Erste Freq:338000 ONID:1 TSID:1101 SID:28106 PMT:0x64 FTA:True LCN:21 SymbolRate:6900 Modulation:Mod256Qam
2013-03-25 16:45:59.437092 [(19)]: dvb:Submiting tunerequest Channel:Das Erste subChannel:-1 
2013-03-25 16:45:59.438092 [(19)]: dvb:Getting new subchannel
2013-03-25 16:45:59.439092 [(19)]: dvb:GetNewSubChannel:0 #0
2013-03-25 16:45:59.443092 [(19)]: TvDvbChannel ctor new subchIndex:0
2013-03-25 16:45:59.444092 [(19)]: dvb:Submit tunerequest size:1 new:0
2013-03-25 16:45:59.445092 [(19)]: subch:0 OnBeforeTune
2013-03-25 16:45:59.446092 [(19)]: dvb:Submit tunerequest calling put_TuneRequest
2013-03-25 16:45:59.447092 [(19)]: dvb:Submit tunerequest done calling put_TuneRequest
2013-03-25 16:45:59.448092 [(19)]: subch:0 OnAfterTune
2013-03-25 16:45:59.449092 [(19)]: RunGraph
2013-03-25 16:45:59.450092 [(19)]: subch:0 OnGraphStart
2013-03-25 16:45:59.450092 [(19)]: dvb:  RunGraph
2013-03-25 16:45:59.671105 [(19)]: dvb:  LockedInOnSignal ok
2013-03-25 16:45:59.673105 [(19)]: subch:0 OnGraphStarted
2013-03-25 16:45:59.676105 [(19)]: subch:0 SetupPmtGrabber:pid 64 sid:6DCA
2013-03-25 16:45:59.677105 [(19)]: subch:0 set pmt grabber pmt:64 sid:6DCA
2013-03-25 16:45:59.680105 [(19)]: TimeShifter.OnAfterTune: resetting audio/video time
2013-03-25 16:45:59.682106 [(19)]: WaitForPMT: Waiting for PMT 64
2013-03-25 16:46:09.683678 [(19)]: WaitForPMT: Timed out waiting for PMT after 10,0015721 seconds. Increase the PMT timeout value?
2013-03-25 16:46:09.685678 [(19)]: Setting to 0 to search for new PMT.
2013-03-25 16:46:09.686678 [(19)]: subch:0 SetupPmtGrabber:pid 0 sid:6DCA
2013-03-25 16:46:09.688678 [(19)]: subch:0 set pmt grabber pmt:0 sid:6DCA
2013-03-25 16:46:09.691678 [(19)]: TimeShifter.OnAfterTune: resetting audio/video time
2013-03-25 16:46:09.692678 [(19)]: WaitForPMT: Waiting for PMT 0
2013-03-25 16:46:19.695250 [(19)]: WaitForPMT: Timed out waiting for PMT after 10,0025721 seconds. Increase the PMT timeout value?
2013-03-25 16:46:19.698250 [(19)]: Setting to 0 to search for new PMT.
2013-03-25 16:46:19.700251 [(19)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2013-03-25 16:46:19.701251 [(19)]: DVB subch:0 Decompose()
2013-03-25 16:46:19.708251 [(19)]: FreeSubChannel CA: freeing sub channel : 0
2013-03-25 16:46:19.709251 [(19)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2013-03-25 16:46:19.710251 [(19)]: dvb:StopGraph called
2013-03-25 16:46:19.712251 [(19)]: tvcard:FreeAllSubChannels
2013-03-25 16:46:19.713251 [(19)]: dvb:StopGraph
2013-03-25 16:46:19.722252 [(19)]: card2:setuptv 2 -1
2013-03-25 16:46:19.723252 [(19)]: Controller: setuptv 2 -1