2011-09-12 23:00:23.745189 [(15)]: card: Tune 6 to Sky Active 2011-09-12 23:00:23.747189 [(15)]: card: user: Sky UK:6:-1 tune DVBS:radio:BSkyB Sky Active Freq:11778000 ONID:2 TSID:2004 SID:8085 PMT:0x136 FTA:False LCN:1744 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate2_3 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:00:23.769189 [(15)]: TimeShifter.OnBeforeTune: resetting audio/video events 2011-09-12 23:00:23.770189 [(15)]: dvbs: Tune:DVBS:radio:BSkyB Sky Active Freq:11778000 ONID:2 TSID:2004 SID:8085 PMT:0x136 FTA:False LCN:1744 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate2_3 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:00:23.772189 [(15)]: dvbs: Tune:DVBS:radio:BSkyB Sky Active Freq:11778000 ONID:2 TSID:2004 SID:8085 PMT:0x136 FTA:False LCN:1744 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate2_3 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:00:23.773189 [(15)]: dvb:GetNewSubChannel:0 #0 2011-09-12 23:00:23.776189 [(15)]: TvDvbChannel ctor new subchIndex:0 2011-09-12 23:00:23.777189 [(15)]: LNB low:9750 hi:10600 switch:11700 2011-09-12 23:00:23.778189 [(15)]: dvbs:channel modulation is set to ModNotSet 2011-09-12 23:00:23.779189 [(15)]: dvbs:channel FECRate is set to Rate2_3 2011-09-12 23:00:23.880189 [(15)]: dvb:Submiting tunerequest Channel:Sky Active subChannel:0 2011-09-12 23:00:23.881189 [(15)]: dvb:using existing subchannel:0 2011-09-12 23:00:23.882189 [(15)]: dvb:Submit tunerequest size:1 new:0 2011-09-12 23:00:23.883189 [(15)]: subch:0 OnBeforeTune 2011-09-12 23:00:23.886189 [(15)]: dvb:Submit tunerequest calling put_TuneRequest 2011-09-12 23:00:23.887189 [(15)]: dvb:Submit tunerequest done calling put_TuneRequest 2011-09-12 23:00:23.888189 [(15)]: subch:0 OnAfterTune 2011-09-12 23:00:23.889189 [(15)]: RunGraph 2011-09-12 23:00:23.890189 [(15)]: subch:0 OnGraphStart 2011-09-12 23:00:23.891189 [(15)]: dvb: RunGraph 2011-09-12 23:00:24.677189 [(15)]: dvb: LockedInOnSignal ok 2011-09-12 23:00:24.679189 [(15)]: subch:0 OnGraphStarted 2011-09-12 23:00:24.680189 [(15)]: subch:0 SetupPmtGrabber:pid 136 sid:1F95 2011-09-12 23:00:24.680189 [(15)]: subch:0 set pmt grabber pmt:136 sid:1F95 2011-09-12 23:00:24.683189 [(15)]: TimeShifter.OnAfterTune: resetting audio/video time 2011-09-12 23:00:24.684189 [(15)]: WaitForPMT: Waiting for PMT 136 2011-09-12 23:00:24.694189 [(16)]: subch:0 OnPMTReceived() pmt:136 ran:True dynamic:False 2011-09-12 23:00:24.696189 [(15)]: WaitForPMT: Found PMT after 0.012 seconds. 2011-09-12 23:00:24.697189 [(15)]: subch:0 SendPmt:1F95 1F95 FFFFFFFF 1 2011-09-12 23:00:24.698189 [(15)]: Decode pmt 2011-09-12 23:00:24.700189 [(15)]: Set descriptor data with length 3 2011-09-12 23:00:24.701189 [(15)]: Set descriptor data with length 3 2011-09-12 23:00:24.702189 [(15)]: subch:0 SendPMT: Channel FTA information changed to True according to CAIDs in PMT. 2011-09-12 23:00:24.703189 [(15)]: subch:0 SendPMT: Channel is FTA, nothing to do. 2011-09-12 23:00:24.703189 [(15)]: subch:0 SetMpegPidMapping 2011-09-12 23:00:24.704189 [(15)]: subch:0 pid:901 pcr 2011-09-12 23:00:24.705189 [(15)]: subch:0 pid:136 pmt 2011-09-12 23:00:24.706189 [(15)]: subch:0 pid:A4E audio lang: type:MPEG-2 2011-09-12 23:00:24.707189 [(15)]: subch:0 map pid:A4E audio lang: type:MPEG-2 2011-09-12 23:00:24.709189 [(15)]: subch:0 pid:FAD type:5 2011-09-12 23:00:24.710189 [(15)]: subch:0 stop tif 2011-09-12 23:00:24.711189 [(15)]: WaitForPmt: PMT handling took 0.014 seconds. 2011-09-12 23:00:24.745189 [(15)]: card: Tuner locked: True 2011-09-12 23:00:24.746189 [(15)]: ************************************************** 2011-09-12 23:00:24.747189 [(15)]: ***** SIGNAL LEVEL: 69, SIGNAL QUALITY: 100 ***** 2011-09-12 23:00:24.748189 [(15)]: ************************************************** 2011-09-12 23:00:24.749189 [(15)]: card: tuned user: Sky UK subchannel: 0 2011-09-12 23:00:24.750189 [(15)]: user:Sky UK add 2011-09-12 23:00:24.753189 [DVB EPG timer(4)]: Controller: GrabEpg on card ID == 6 2011-09-12 23:00:24.757189 [DVB EPG timer(4)]: dvb:grab epg... 2011-09-12 23:00:27.327189 [TS_File_Cleanup(14)]: card: delete timeshift files D:\\live6-0.ts 2011-09-12 23:00:27.328189 [TS_File_Cleanup(14)]: card: trying to delete D:\live6-0.ts.tsbuffer 2011-09-12 23:00:27.333189 [TS_File_Cleanup(14)]: card: deleted file D:\live6-0.ts.tsbuffer 2011-09-12 23:00:27.334189 [TS_File_Cleanup(14)]: card: trying to delete D:\live6-0.ts.tsbuffer1.ts 2011-09-12 23:00:27.343189 [TS_File_Cleanup(14)]: card: deleted file D:\live6-0.ts.tsbuffer1.ts 2011-09-12 23:01:17.654189 [(15)]: Controller: find free card for channel ITV2 2011-09-12 23:01:17.655189 [(15)]: Controller: find card for channel ITV2 2011-09-12 23:01:17.659189 [(15)]: Controller: got 1 tuning details for ITV2 2011-09-12 23:01:17.661189 [(15)]: Controller: channel #1 DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:17.662189 [(15)]: Controller: card:1 type:DvbIP is disabled 2011-09-12 23:01:17.663189 [(15)]: Controller: card:2 type:RadioWebStream is disabled 2011-09-12 23:01:17.664189 [(15)]: Controller: card:3 type:DvbC is disabled 2011-09-12 23:01:17.665189 [(15)]: Controller: card:5 type:DvbT is disabled 2011-09-12 23:01:17.666189 [(15)]: Controller: card:14 type:DvbT is disabled 2011-09-12 23:01:17.667189 [(15)]: Controller: card:12 type:DvbC is disabled 2011-09-12 23:01:17.668189 [(15)]: Controller: card:7 type:Analog is disabled 2011-09-12 23:01:17.669189 [(15)]: Controller: card:16 type:Analog is disabled 2011-09-12 23:01:17.672189 [(15)]: Controller: card:6 type:DvbS can tune to channel 2011-09-12 23:01:17.676189 [(15)]: Controller: card:6 type:DvbS users: 1 2011-09-12 23:01:17.680189 [(15)]: Controller: card:15 type:DvbS can tune to channel 2011-09-12 23:01:17.681189 [(15)]: Controller: card:15 type:DvbS users: 0 2011-09-12 23:01:17.682189 [(15)]: Controller: card:4 type:DvbT cannot tune to channel 2011-09-12 23:01:17.683189 [(15)]: Controller: card:13 type:DvbT cannot tune to channel 2011-09-12 23:01:17.685189 [(15)]: Controller: found 2 card(s) for channel 2011-09-12 23:01:17.686189 [(15)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 30 msec 2011-09-12 23:01:17.687189 [(15)]: Controller: card:15 type:DvbS is available 2011-09-12 23:01:17.688189 [(15)]: Controller: card:6 type:DvbS is not available, tuned to different transponder 2011-09-12 23:01:17.689189 [(15)]: Controller: found 1 free card(s) 2011-09-12 23:01:17.689189 [(15)]: AdvancedCardAllocation.GetFreeCardsForChannel took 36 msec 2011-09-12 23:01:17.697189 [(15)]: Controller: TimeShiftingWouldUseCard ITV2 1570 2011-09-12 23:01:17.701189 [(15)]: Controller: find free card for channel ITV2 2011-09-12 23:01:17.702189 [(15)]: Controller: find card for channel ITV2 2011-09-12 23:01:17.707189 [(15)]: Controller: got 1 tuning details for ITV2 2011-09-12 23:01:17.708189 [(15)]: Controller: channel #1 DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:17.709189 [(15)]: Controller: card:1 type:DvbIP is disabled 2011-09-12 23:01:17.711189 [(15)]: Controller: card:2 type:RadioWebStream is disabled 2011-09-12 23:01:17.712189 [(15)]: Controller: card:3 type:DvbC is disabled 2011-09-12 23:01:17.713189 [(15)]: Controller: card:5 type:DvbT is disabled 2011-09-12 23:01:17.714189 [(15)]: Controller: card:14 type:DvbT is disabled 2011-09-12 23:01:17.715189 [(15)]: Controller: card:12 type:DvbC is disabled 2011-09-12 23:01:17.715189 [(15)]: Controller: card:7 type:Analog is disabled 2011-09-12 23:01:17.717189 [(15)]: Controller: card:16 type:Analog is disabled 2011-09-12 23:01:17.723189 [(15)]: Controller: card:6 type:DvbS can tune to channel 2011-09-12 23:01:17.724189 [(15)]: Controller: card:6 type:DvbS users: 1 2011-09-12 23:01:17.726189 [(15)]: Controller: card:15 type:DvbS can tune to channel 2011-09-12 23:01:17.727189 [(15)]: Controller: card:15 type:DvbS users: 0 2011-09-12 23:01:17.729189 [(15)]: Controller: card:4 type:DvbT cannot tune to channel 2011-09-12 23:01:17.730189 [(15)]: Controller: card:13 type:DvbT cannot tune to channel 2011-09-12 23:01:17.731189 [(15)]: Controller: found 2 card(s) for channel 2011-09-12 23:01:17.732189 [(15)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 30 msec 2011-09-12 23:01:17.733189 [(15)]: Controller: card:15 type:DvbS is available 2011-09-12 23:01:17.734189 [(15)]: Controller: card:6 type:DvbS is not available, tuned to different transponder 2011-09-12 23:01:17.735189 [(15)]: Controller: found 1 free card(s) 2011-09-12 23:01:17.736189 [(15)]: AdvancedCardAllocation.GetFreeCardsForChannel took 38 msec 2011-09-12 23:01:17.741189 [(15)]: CiMenuSupported called cardid 15 2011-09-12 23:01:17.742189 [(15)]: CiMenuSupported card 7231 BDA DVBS Tuner supported: False 2011-09-12 23:01:17.761189 [(15)]: Controller: StartTimeShifting ITV2 1570 2011-09-12 23:01:17.765189 [(15)]: user:Sky UK remove 2011-09-12 23:01:17.766189 [(15)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False 2011-09-12 23:01:17.767189 [(15)]: DVB subch:0 Decompose() 2011-09-12 23:01:17.772189 [(15)]: FreeSubChannel CA: freeing sub channel : 0 2011-09-12 23:01:17.773189 [(15)]: tvcard:FreeSubChannel : no subchannels present, pausing graph 2011-09-12 23:01:17.774189 [(15)]: dvb:StopGraph called 2011-09-12 23:01:17.775189 [(15)]: tvcard:FreeAllSubChannels 2011-09-12 23:01:17.776189 [(15)]: dvb:StopGraph 2011-09-12 23:01:17.808189 [(15)]: Controller: find free card for channel ITV2 2011-09-12 23:01:17.810189 [(15)]: Controller: find card for channel ITV2 2011-09-12 23:01:17.814189 [(15)]: Controller: got 1 tuning details for ITV2 2011-09-12 23:01:17.815189 [(15)]: Controller: channel #1 DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:17.816189 [(15)]: Controller: card:1 type:DvbIP is disabled 2011-09-12 23:01:17.817189 [(15)]: Controller: card:2 type:RadioWebStream is disabled 2011-09-12 23:01:17.819189 [(15)]: Controller: card:3 type:DvbC is disabled 2011-09-12 23:01:17.820189 [(15)]: Controller: card:5 type:DvbT is disabled 2011-09-12 23:01:17.821189 [(15)]: Controller: card:14 type:DvbT is disabled 2011-09-12 23:01:17.822189 [(15)]: Controller: card:12 type:DvbC is disabled 2011-09-12 23:01:17.823189 [(15)]: Controller: card:7 type:Analog is disabled 2011-09-12 23:01:17.824189 [(15)]: Controller: card:16 type:Analog is disabled 2011-09-12 23:01:17.831189 [(15)]: Controller: card:6 type:DvbS can tune to channel 2011-09-12 23:01:17.832189 [(15)]: Controller: card:6 type:DvbS users: 0 2011-09-12 23:01:17.835189 [(15)]: Controller: card:15 type:DvbS can tune to channel 2011-09-12 23:01:17.836189 [(15)]: Controller: card:15 type:DvbS users: 0 2011-09-12 23:01:17.837189 [(15)]: Controller: card:4 type:DvbT cannot tune to channel 2011-09-12 23:01:17.839189 [(15)]: Controller: card:13 type:DvbT cannot tune to channel 2011-09-12 23:01:17.840189 [(15)]: Controller: found 2 card(s) for channel 2011-09-12 23:01:17.841189 [(15)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 31 msec 2011-09-12 23:01:17.842189 [(15)]: Controller: card:6 type:DvbS is available 2011-09-12 23:01:17.844189 [(15)]: Controller: card:15 type:DvbS is available 2011-09-12 23:01:17.845189 [(15)]: Controller: found 2 free card(s) 2011-09-12 23:01:17.846189 [(15)]: AdvancedCardAllocation.GetFreeCardsForChannel took 37 msec 2011-09-12 23:01:17.847189 [(15)]: Controller: try max 2 of 2 cards for timeshifting 2011-09-12 23:01:17.856189 [(15)]: card: CardTune 6 ITV2 htpc:6:-1 2011-09-12 23:01:17.858189 [(15)]: card: Tune 6 to ITV2 2011-09-12 23:01:17.859189 [(15)]: card: user: htpc:6:-1 tune DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:17.886189 [(15)]: TimeShifter.OnBeforeTune: resetting audio/video events 2011-09-12 23:01:17.888189 [(15)]: dvbs: Tune:DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:17.889189 [(15)]: dvbs: Tune:DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:17.890189 [(15)]: dvb:GetNewSubChannel:0 #0 2011-09-12 23:01:17.894189 [(15)]: TvDvbChannel ctor new subchIndex:0 2011-09-12 23:01:17.895189 [(15)]: LNB low:9750 hi:10600 switch:11700 2011-09-12 23:01:17.897189 [(15)]: dvbs:channel modulation is set to ModNotSet 2011-09-12 23:01:17.901189 [(15)]: dvbs:channel FECRate is set to Rate5_6 2011-09-12 23:01:18.002189 [(15)]: dvb:Submiting tunerequest Channel:ITV2 subChannel:0 2011-09-12 23:01:18.003189 [(15)]: dvb:using existing subchannel:0 2011-09-12 23:01:18.004189 [(15)]: dvb:Submit tunerequest size:1 new:0 2011-09-12 23:01:18.005189 [(15)]: subch:0 OnBeforeTune 2011-09-12 23:01:18.008189 [(15)]: dvb:Submit tunerequest calling put_TuneRequest 2011-09-12 23:01:18.009189 [(15)]: dvb:Submit tunerequest done calling put_TuneRequest 2011-09-12 23:01:18.010189 [(15)]: subch:0 OnAfterTune 2011-09-12 23:01:18.011189 [(15)]: RunGraph 2011-09-12 23:01:18.012189 [(15)]: subch:0 OnGraphStart 2011-09-12 23:01:18.013189 [(15)]: dvb: RunGraph 2011-09-12 23:01:18.445189 [EPG Update thread(14)]: user:Sky UK remove 2011-09-12 23:01:18.446189 [EPG Update thread(14)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False 2011-09-12 23:01:18.447189 [EPG Update thread(14)]: DVB subch:0 Decompose() 2011-09-12 23:01:18.451189 [EPG Update thread(14)]: FreeSubChannel CA: freeing sub channel : 0 2011-09-12 23:01:18.452189 [EPG Update thread(14)]: tvcard:FreeSubChannel : no subchannels present, pausing graph 2011-09-12 23:01:18.453189 [EPG Update thread(14)]: dvb:StopGraph called 2011-09-12 23:01:18.454189 [EPG Update thread(14)]: tvcard:FreeAllSubChannels 2011-09-12 23:01:18.799189 [EPG Update thread(14)]: dvb:StopGraph 2011-09-12 23:01:18.801189 [(15)]: card: Tuner locked: False 2011-09-12 23:01:18.802189 [(15)]: ************************************************** 2011-09-12 23:01:18.803189 [(15)]: ***** SIGNAL LEVEL: 0, SIGNAL QUALITY: 0 ***** 2011-09-12 23:01:18.803189 [(15)]: ************************************************** 2011-09-12 23:01:18.804189 [(15)]: card: tuned user: htpc subchannel: 0 2011-09-12 23:01:18.805189 [(15)]: user:htpc add 2011-09-12 23:01:18.806189 [(15)]: card2:htpc 6 0 2011-09-12 23:01:18.807189 [(15)]: Controller: htpc 6 0 2011-09-12 23:01:18.810189 [(15)]: control2:htpc 6 0 2011-09-12 23:01:18.814189 [(15)]: Controller: delete timeshift files D:\\live6-0.ts 2011-09-12 23:01:18.831189 [(15)]: card: StartTimeShifting 6 D:\\live6-0.ts 2011-09-12 23:01:18.833189 [(15)]: card 7231 BDA DVBS Tuner: StopTimeShifting user:htpc sub:0 2011-09-12 23:01:18.836189 [(15)]: card: StopTimeShifting user:htpc sub:0 2011-09-12 23:01:18.837189 [(15)]: card not IDLE - removing user: htpc 2011-09-12 23:01:18.838189 [(15)]: user:htpc remove 2011-09-12 23:01:18.839189 [(15)]: dvb:StopGraph called 2011-09-12 23:01:18.840189 [(15)]: tvcard:FreeAllSubChannels 2011-09-12 23:01:18.854189 [(15)]: dvb:StopGraph filterstate already stopped, returning. 2011-09-12 23:01:18.855189 [(15)]: user:htpc remove 2011-09-12 23:01:18.856189 [EPG Update thread(14)]: Pausecard 2011-09-12 23:01:18.858189 [EPG Update thread(14)]: dvb:StopGraph called 2011-09-12 23:01:18.859189 [EPG Update thread(14)]: tvcard:FreeAllSubChannels 2011-09-12 23:01:18.860189 [EPG Update thread(14)]: dvb:StopGraph filterstate already stopped, returning. 2011-09-12 23:01:18.861189 [(15)]: Controller: Timeshifting failed, lets try next available card. 2011-09-12 23:01:18.865189 [(15)]: card: CardTune 15 ITV2 htpc:15:-1 2011-09-12 23:01:18.867189 [(15)]: card: Tune 15 to ITV2 2011-09-12 23:01:18.868189 [(15)]: card: user: htpc:15:-1 tune DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:18.891189 [(15)]: TimeShifter.OnBeforeTune: resetting audio/video events 2011-09-12 23:01:18.893189 [(15)]: dvbs: Tune:DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:18.894189 [(15)]: dvbs: Tune:DVBS:tv:BSkyB ITV2 Freq:10758000 ONID:2 TSID:2044 SID:10070 PMT:0x106 FTA:True LCN:1570 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet 2011-09-12 23:01:18.894189 [(15)]: dvb:GetNewSubChannel:0 #0 2011-09-12 23:01:18.898189 [(15)]: TvDvbChannel ctor new subchIndex:0 2011-09-12 23:01:18.899189 [(15)]: dvb:Submiting tunerequest Channel:ITV2 subChannel:0 2011-09-12 23:01:18.900189 [(15)]: dvb:using existing subchannel:0 2011-09-12 23:01:18.901189 [(15)]: dvb:Submit tunerequest size:1 new:0 2011-09-12 23:01:18.902189 [(15)]: subch:0 OnBeforeTune 2011-09-12 23:01:18.904189 [(15)]: subch:0 OnAfterTune 2011-09-12 23:01:18.905189 [(15)]: RunGraph 2011-09-12 23:01:18.906189 [(15)]: subch:0 OnGraphStart 2011-09-12 23:01:18.907189 [(15)]: dvb: RunGraph 2011-09-12 23:01:18.912189 [(15)]: dvb: LockedInOnSignal ok 2011-09-12 23:01:18.913189 [(15)]: subch:0 OnGraphStarted 2011-09-12 23:01:18.914189 [(15)]: subch:0 SetupPmtGrabber:pid 106 sid:2756 2011-09-12 23:01:18.915189 [(15)]: subch:0 set pmt grabber pmt:106 sid:2756 2011-09-12 23:01:18.916189 [(15)]: TimeShifter.OnAfterTune: resetting audio/video time 2011-09-12 23:01:18.921189 [(15)]: WaitForPMT: Waiting for PMT 106 2011-09-12 23:01:19.047189 [(3)]: subch:0 OnPMTReceived() pmt:106 ran:True dynamic:False 2011-09-12 23:01:19.049189 [(15)]: WaitForPMT: Found PMT after 0.128 seconds. 2011-09-12 23:01:19.050189 [(15)]: subch:0 SendPmt:2756 2756 FFFFFFFF 19 2011-09-12 23:01:19.051189 [(15)]: Decode pmt 2011-09-12 23:01:19.052189 [(15)]: Set descriptor data with length 3 2011-09-12 23:01:19.053189 [(15)]: Set descriptor data with length 6 2011-09-12 23:01:19.054189 [(15)]: PROBLEM : descriptor lengths dont match 3 9 2011-09-12 23:01:19.055189 [(15)]: Set descriptor data with length 6 2011-09-12 23:01:19.056189 [(15)]: PROBLEM : descriptor lengths dont match 3 9 2011-09-12 23:01:19.057189 [(15)]: Set descriptor data with length 12 2011-09-12 23:01:19.058189 [(15)]: PROBLEM : descriptor lengths dont match 3 15 2011-09-12 23:01:19.059189 [(15)]: Set descriptor data with length 3 2011-09-12 23:01:19.060189 [(15)]: Set descriptor data with length 3 2011-09-12 23:01:19.061189 [(15)]: Set descriptor data with length 3 2011-09-12 23:01:19.062189 [(15)]: Set descriptor data with length 3 2011-09-12 23:01:19.063189 [(15)]: Set descriptor data with length 3 2011-09-12 23:01:19.064189 [(15)]: Set descriptor data with length 10 2011-09-12 23:01:19.065189 [(15)]: subch:0 SendPMT: Channel is FTA, nothing to do. 2011-09-12 23:01:19.066189 [(15)]: subch:0 SetMpegPidMapping 2011-09-12 23:01:19.067189 [(15)]: subch:0 pid:D04 pcr 2011-09-12 23:01:19.068189 [(15)]: subch:0 pid:106 pmt 2011-09-12 23:01:19.069189 [(15)]: subch:0 pid:D04 video type:MPEG-2 2011-09-12 23:01:19.070189 [(15)]: subch:0 map pid:D04 video type:MPEG-2 2011-09-12 23:01:19.071189 [(15)]: subch:0 pid:D05 audio lang: type:MPEG-1 2011-09-12 23:01:19.072189 [(15)]: subch:0 map pid:D05 audio lang: type:MPEG-1 2011-09-12 23:01:19.074189 [(15)]: subch:0 pid:D06 audio lang:NAR type:MPEG-1 2011-09-12 23:01:19.075189 [(15)]: subch:0 pid:93F teletext type:6 2011-09-12 23:01:19.076189 [(15)]: subch:0 map pid:93F teletext type:6 2011-09-12 23:01:19.077189 [(15)]: subch:0 pid:902 type:5 2011-09-12 23:01:19.078189 [(15)]: subch:0 pid:903 type:5 2011-09-12 23:01:19.079189 [(15)]: subch:0 pid:904 type:5 2011-09-12 23:01:19.080189 [(15)]: subch:0 pid:905 type:5 2011-09-12 23:01:19.080189 [(15)]: subch:0 pid:906 type:5 2011-09-12 23:01:19.081189 [(15)]: subch:0 pid:907 type:5 2011-09-12 23:01:19.082189 [(15)]: subch:0 pid:908 type:5 2011-09-12 23:01:19.083189 [(15)]: subch:0 pid:909 type:5 2011-09-12 23:01:19.084189 [(15)]: subch:0 pid:90A type:5 2011-09-12 23:01:19.085189 [(15)]: subch:0 pid:90B type:5 2011-09-12 23:01:19.086189 [(15)]: subch:0 pid:90C type:5 2011-09-12 23:01:19.087189 [(15)]: subch:0 pid:90D type:5 2011-09-12 23:01:19.088189 [(15)]: subch:0 pid:90E type:5 2011-09-12 23:01:19.090189 [(15)]: subch:0 pid:90F type:5 2011-09-12 23:01:19.091189 [(15)]: subch:0 pid:910 type:5 2011-09-12 23:01:19.091189 [(15)]: subch:0 pid:F00 type:5 2011-09-12 23:01:19.092189 [(15)]: subch:0 pid:F01 type:5 2011-09-12 23:01:19.093189 [(15)]: subch:0 pid:F02 type:5 2011-09-12 23:01:19.094189 [(15)]: subch:0 pid:F03 type:5 2011-09-12 23:01:19.095189 [(15)]: subch:0 pid:F04 type:5 2011-09-12 23:01:19.096189 [(15)]: subch:0 pid:F05 type:B 2011-09-12 23:01:19.097189 [(15)]: subch:0 pid:F06 type:B 2011-09-12 23:01:19.098189 [(15)]: subch:0 pid:939 type:5 2011-09-12 23:01:19.099189 [(15)]: subch:0 pid:F0A type:B 2011-09-12 23:01:19.100189 [(15)]: subch:0 pid:940 subtitle type:6 2011-09-12 23:01:19.101189 [(15)]: subch:0 map pid:940 subtitle type:6 2011-09-12 23:01:19.102189 [(15)]: subch:0 stop tif 2011-09-12 23:01:19.103189 [(15)]: WaitForPmt: PMT handling took 0.053 seconds. 2011-09-12 23:01:19.136189 [(15)]: card: Tuner locked: True 2011-09-12 23:01:19.137189 [(15)]: ************************************************** 2011-09-12 23:01:19.138189 [(15)]: ***** SIGNAL LEVEL: 68, SIGNAL QUALITY: 100 ***** 2011-09-12 23:01:19.139189 [(15)]: ************************************************** 2011-09-12 23:01:19.140189 [(15)]: card: tuned user: htpc subchannel: 0 2011-09-12 23:01:19.141189 [(15)]: user:htpc add 2011-09-12 23:01:19.141189 [(15)]: card2:htpc 15 0 2011-09-12 23:01:19.142189 [(15)]: Controller: htpc 15 0 2011-09-12 23:01:19.144189 [(15)]: control2:htpc 15 0 2011-09-12 23:01:19.150189 [(15)]: Controller: delete timeshift files D:\\live15-0.ts 2011-09-12 23:01:19.159189 [(15)]: card: StartTimeShifting 15 D:\\live15-0.ts 2011-09-12 23:01:19.161189 [(15)]: card: CAM enabled : False 2011-09-12 23:01:19.162189 [(15)]: subch:0 SetTimeShiftFileName:D:\\live15-0.ts 2011-09-12 23:01:19.162189 [(15)]: Set video / audio observer 2011-09-12 23:01:19.165189 [(15)]: subch:0 SetTimeShiftFileName fill in pids 2011-09-12 23:01:19.182189 [(15)]: subch:0-0 tswriter StartTimeshifting... 2011-09-12 23:01:19.187189 [(15)]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo 2011-09-12 23:01:19.221189 [(3)]: PID seen - type = Audio 2011-09-12 23:01:19.222189 [(3)]: audioVideoEventHandler Audio 2011-09-12 23:01:19.237189 [(3)]: PID seen - type = Video 2011-09-12 23:01:19.238189 [(3)]: audioVideoEventHandler Video 2011-09-12 23:01:19.239189 [(15)]: card: WaitForTimeShiftFile - video and audio are seen after 0.051 seconds 2011-09-12 23:01:19.252189 [(15)]: TimeshiftingEPG: channel ITV2 is not configured for grabbing epg 2011-09-12 23:01:19.253189 [(15)]: user:htpc card:15 sub:0 add stream:D:\\live15-0.ts.tsbuffer 2011-09-12 23:01:19.254189 [(15)]: RTSP: add stream stream15.0 file:D:\\live15-0.ts.tsbuffer 2011-09-12 23:01:19.257189 [(15)]: Controller: StartTimeShifting started on card:15 to D:\\live15-0.ts.tsbuffer 2011-09-12 23:01:19.262189 [Channel state thread(7)]: Controller: DoSetChannelStates for 825 channels 2011-09-12 23:01:19.286189 [(15)]: CiMenuSupported called cardid 15 2011-09-12 23:01:19.287189 [(15)]: CiMenuSupported card 7231 BDA DVBS Tuner supported: False 2011-09-12 23:01:19.300189 [(3)]: PID seen - type = Audio 2011-09-12 23:01:19.301189 [(3)]: audio last seen at 12/09/2011 23:01:19 2011-09-12 23:01:19.486189 [Channel state thread(7)]: ChannelStates.GetChannelMapping took 222 msec 2011-09-12 23:01:19.758189 [Channel state thread(7)]: ChannelStates.GetTuningChannels took 270 msec 2011-09-12 23:01:19.767189 [Channel state thread(7)]: ChannelStates.GetAllTimeshiftingAndRecordingChannels took 8 msec 2011-09-12 23:01:19.774189 [Channel state thread(7)]: ChannelStates.DoSetChannelStates took 512 msec