[2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: find free card for channel TV3 STOCKHOLM [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: find card for channel TV3 STOCKHOLM [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: got 1 tuning details for TV3 STOCKHOLM [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: channel #1 DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT users: 0 [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: found 1 card(s) for channel [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 2 msec [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT is available [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: found 1 free card(s) [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 3 msec [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: TimeShiftingWouldUseCard TV3 STOCKHOLM 69 [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: find free card for channel TV3 STOCKHOLM [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: find card for channel TV3 STOCKHOLM [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: got 1 tuning details for TV3 STOCKHOLM [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: channel #1 DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-03-26 01:10:54,457] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel [2014-03-26 01:10:54,467] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT users: 0 [2014-03-26 01:10:54,467] [Log ] [18 ] [INFO ] - Controller: found 1 card(s) for channel [2014-03-26 01:10:54,467] [Log ] [18 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 2 msec [2014-03-26 01:10:54,467] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT is available [2014-03-26 01:10:54,467] [Log ] [18 ] [INFO ] - Controller: found 1 free card(s) [2014-03-26 01:10:54,467] [Log ] [18 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 3 msec [2014-03-26 01:10:54,467] [Log ] [18 ] [DEBUG] - CiMenuSupported called cardid 3 [2014-03-26 01:10:54,467] [Log ] [18 ] [DEBUG] - CiMenuSupported card DTV-DVB Mantis DVBT BDA Receiver supported: True [2014-03-26 01:10:54,467] [Log ] [18 ] [DEBUG] - CiMenu: registered client event for callback [2014-03-26 01:10:54,467] [Log ] [18 ] [DEBUG] - TvController: EnableCiMenuHandler called [2014-03-26 01:10:54,467] [Log ] [18 ] [DEBUG] - Twinhan: registering ci callbacks [2014-03-26 01:10:54,467] [Log ] [18 ] [DEBUG] - TvController: SetCiMenuHandler: result True [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: StartTimeShifting TV3 STOCKHOLM 69 [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: epg stop [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: find free card for channel TV3 STOCKHOLM [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: find card for channel TV3 STOCKHOLM [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: got 1 tuning details for TV3 STOCKHOLM [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: channel #1 DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: found 1 card(s) for channel [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 2 msec [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: found 1 free card(s) [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 2 msec [2014-03-26 01:10:54,477] [Log ] [18 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=3, tuningdetails=DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT users: 0 [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: card:3 type:DvbT is available [2014-03-26 01:10:54,477] [Log ] [18 ] [INFO ] - Controller: try max 1 of 1 cards for timeshifting [2014-03-26 01:10:54,477] [Log ] [18 ] [DEBUG] - CardReservationBase: tvcard=3, user=HTPC-Dator, dbChannel=69, ticket=3, tunestate=Tuning, stopstate=Idle [2014-03-26 01:10:54,527] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - card: CardTune 3 TV3 STOCKHOLM HTPC-Dator:3:-1 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - card: Tune on card 3 to subchannel TV3 STOCKHOLM [2014-03-26 01:10:54,745] [Log ] [18 ] [DEBUG] - card: user: HTPC-Dator:3:-1 tune DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:54,745] [Log ] [18 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - dvbt: Tune:DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - dvb:Submiting tunerequest Channel:TV3 STOCKHOLM subChannel:-1 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - dvb:Getting new subchannel [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - dvb:GetNewSubChannel:0 #0 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - TvDvbChannel ctor new subchIndex:0 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - card: AddTuneEvent card: 3 / subch: 0 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - dvb:Submit tunerequest size:1 new:0 [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - subch:0 OnBeforeTune [2014-03-26 01:10:54,745] [Log ] [18 ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest [2014-03-26 01:10:54,761] [Log ] [18 ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest [2014-03-26 01:10:54,761] [Log ] [18 ] [INFO ] - subch:0 OnAfterTune [2014-03-26 01:10:54,761] [Log ] [18 ] [INFO ] - RunGraph [2014-03-26 01:10:54,761] [Log ] [18 ] [INFO ] - subch:0 OnGraphStart [2014-03-26 01:10:54,761] [Log ] [18 ] [INFO ] - dvb: RunGraph [2014-03-26 01:10:55,042] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:55,556] [Log ] [18 ] [INFO ] - dvb: LockInOnSignal ok [2014-03-26 01:10:55,556] [Log ] [18 ] [INFO ] - subch:0 OnGraphStarted [2014-03-26 01:10:55,556] [Log ] [18 ] [INFO ] - subch:0 SetupPmtGrabber:pid BB8 sid:BB8 [2014-03-26 01:10:55,556] [Log ] [18 ] [INFO ] - subch:0 set pmt grabber pmt:BB8 sid:BB8 [2014-03-26 01:10:55,556] [Log ] [18 ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time [2014-03-26 01:10:55,556] [Log ] [18 ] [DEBUG] - WaitForPMT: Waiting for PMT BB8 [2014-03-26 01:10:55,556] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:55,650] [Log ] [19 ] [INFO ] - subch:0 OnPMTReceived() pmt:BB8 ran:True dynamic:False [2014-03-26 01:10:55,650] [Log ] [18 ] [DEBUG] - WaitForPMT: Found PMT after 0,0936002 seconds. [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - subch:0 SendPmt:BB8 BB8 FFFFFFFF 1 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Decode pmt [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC1 len:11 9 f 5 0 e0 32 10 1 0 13 1 20 14 3 2 5 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC1 len:11 9 f 5 0 e0 68 10 1 0 13 1 20 14 3 2 32 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC1 len:11 9 f 5 0 e0 81 10 1 0 13 1 20 14 3 4 d 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Set descriptor data with length 6 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC0 len:11 9 f 5 0 e0 32 10 1 0 13 1 20 14 3 2 5 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC0 len:11 9 f 5 0 e0 68 10 1 0 13 1 20 14 3 2 32 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC0 len:11 9 f 5 0 e0 81 10 1 0 13 1 20 14 3 4 d 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Set descriptor data with length 7 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - subch:0 SendPMT version:1 len:152 3000 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Decode pmt [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC1 len:11 9 f 5 0 e0 32 10 1 0 13 1 20 14 3 2 5 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC1 len:11 9 f 5 0 e0 68 10 1 0 13 1 20 14 3 2 32 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC1 len:11 9 f 5 0 e0 81 10 1 0 13 1 20 14 3 4 d 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Set descriptor data with length 6 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC0 len:11 9 f 5 0 e0 32 10 1 0 13 1 20 14 3 2 5 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC0 len:11 9 f 5 0 e0 68 10 1 0 13 1 20 14 3 2 32 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - descr2 pid:BC0 len:11 9 f 5 0 e0 81 10 1 0 13 1 20 14 3 4 d 8 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Set descriptor data with length 7 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - Twinhan: Send PMT, len: 120 [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - capmt: [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0000|03 0B B8 C3 00 00 02 EB C1 00 34 01 09 0F 05 00 |__________4_____ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0010|E0 32 10 01 00 13 01 20 14 03 02 05 08 09 0F 05 |_2_____ ________ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0020|00 E0 68 10 01 00 13 01 20 14 03 02 32 08 09 0F |__h_____ ___2___ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0030|05 00 E0 81 10 01 00 13 01 20 14 03 04 0D 08 03 |_________ ______ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0040|EB C0 00 34 01 09 0F 05 00 E0 32 10 01 00 13 01 |___4______2_____ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0050|20 14 03 02 05 08 09 0F 05 00 E0 68 10 01 00 13 | __________h____ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0060|01 20 14 03 02 32 08 09 0F 05 00 E0 81 10 01 00 |_ ___2__________ [2014-03-26 01:10:55,650] [Log ] [18 ] [INFO ] - 0070|13 01 20 14 03 04 0D 08 |__ _____ [2014-03-26 01:10:56,071] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:56,586] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - Twinhan: CAM returned ok 0x0 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 cam flags:True [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 SetMpegPidMapping [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 pid:BC1 pcr [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 pid:BB8 pmt [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 pid:BC1 video type:MPEG-2 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 map pid:BC1 video type:MPEG-2 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 pid:BC0 audio lang:swe type:MPEG-1 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 map pid:BC0 audio lang:swe type:MPEG-1 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 pid:BBE teletext type:6 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 map pid:BBE teletext type:6 [2014-03-26 01:10:56,664] [Log ] [18 ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 stop tif [2014-03-26 01:10:56,664] [Log ] [18 ] [DEBUG] - WaitForPmt: PMT handling took 1,0140017 seconds. [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel [2014-03-26 01:10:56,664] [Log ] [18 ] [DEBUG] - card: Tuner locked: True [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - ************************************************** [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - ***** SIGNAL LEVEL: 44, SIGNAL QUALITY: 44 ***** [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - ************************************************** [2014-03-26 01:10:56,664] [Log ] [18 ] [DEBUG] - card: tuned user: HTPC-Dator subchannel: 0 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - user:HTPC-Dator add [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - card2:HTPC-Dator 3 0 [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - Controller: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - Controller: epg stop [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - card: StartTimeShifting 3 C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - card: CAM enabled : True [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 SetTimeShiftFileName:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - Set video / audio observer [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0 SetTimeShiftFileName fill in pids [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - subch:0-0 tswriter StartTimeshifting... [2014-03-26 01:10:56,664] [Log ] [18 ] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo [2014-03-26 01:10:56,758] [Log ] [19 ] [INFO ] - PID seen - type = Video [2014-03-26 01:10:56,758] [Log ] [19 ] [INFO ] - audioVideoEventHandler Video [2014-03-26 01:10:56,758] [Log ] [19 ] [INFO ] - PID seen - type = Audio [2014-03-26 01:10:56,758] [Log ] [19 ] [INFO ] - audioVideoEventHandler Audio [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - card: WaitForFile - video and audio are seen after 0,0936002 seconds [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - TimeshiftingEPG: channel TV3 STOCKHOLM is not configured for grabbing epg [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - user:HTPC-Dator card:3 sub:0 add stream:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - RTSP: add stream stream3.0 file:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - Controller: StartTimeShifting started on card:3 to C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:10:56,758] [Log ] [18 ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=3, tuningdetails=DVBT:tv:Viasat AB TV3 STOCKHOLM Freq:754000 ONID:8945 TSID:1110 SID:3000 PMT:0xBB8 FTA:False LCN:3 BandWidth:8 [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - card: SignalTuneEvent card: 3 / subch: 0 [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - Controller: HTPC-Dator 3 0 [2014-03-26 01:10:56,758] [Log ] [18 ] [INFO ] - control2:HTPC-Dator 3 0 [2014-03-26 01:10:56,773] [Log ] [18 ] [DEBUG] - CiMenuSupported called cardid 3 [2014-03-26 01:10:56,773] [Log ] [18 ] [DEBUG] - CiMenuSupported card DTV-DVB Mantis DVBT BDA Receiver supported: True [2014-03-26 01:10:56,773] [Log ] [18 ] [DEBUG] - CiMenu: registered client event for callback [2014-03-26 01:10:56,773] [Log ] [21 ] [DEBUG] - TvController: EnableCiMenuHandler called [2014-03-26 01:10:56,773] [Log ] [21 ] [DEBUG] - Twinhan: registering ci callbacks [2014-03-26 01:10:56,773] [Log ] [21 ] [DEBUG] - TvController: SetCiMenuHandler: result True [2014-03-26 01:10:57,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:57,587] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:58,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:58,587] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:10:59,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:03,758] [Log ] [18 ] [DEBUG] - Controller: DoSetChannelStates for 47 channels [2014-03-26 01:11:03,760] [Log ] [18 ] [INFO ] - ChannelStates.GetAllTimeshiftingAndRecordingChannels took 0 msec [2014-03-26 01:11:03,812] [Log ] [18 ] [INFO ] - ChannelStates.DoSetChannelStates took 54 msec [2014-03-26 01:11:04,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:04,587] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:05,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:05,587] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:06,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:06,775] [Log ] [18 ] [INFO ] - Controller: StopTimeShifting 3 [2014-03-26 01:11:06,777] [Log ] [18 ] [DEBUG] - CardTuner.RequestCardStopReservation: placed reservation with id=4, user=HTPC-Dator [2014-03-26 01:11:06,877] [Log ] [18 ] [DEBUG] - CardTuner.Stop: ticket id=4, found=True [2014-03-26 01:11:06,877] [Log ] [18 ] [INFO ] - Stop cardid=3, ticket=4, tunestate=Idle, stopstate=Stopping [2014-03-26 01:11:06,877] [Log ] [18 ] [INFO ] - card DTV-DVB Mantis DVBT BDA Receiver: StopTimeShifting user:HTPC-Dator sub:0 [2014-03-26 01:11:06,877] [Log ] [18 ] [DEBUG] - card not IDLE - removing user: HTPC-Dator [2014-03-26 01:11:06,878] [Log ] [18 ] [DEBUG] - usermanagement.RemoveUser: HTPC-Dator, subch: 0 of 1, card: 3 [2014-03-26 01:11:06,878] [Log ] [18 ] [INFO ] - user:HTPC-Dator remove [2014-03-26 01:11:06,889] [Log ] [18 ] [INFO ] - subch:0-0 tswriter StopTimeshifting... [2014-03-26 01:11:06,889] [Log ] [18 ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0 [2014-03-26 01:11:06,889] [Log ] [18 ] [INFO ] - DVB subch:0 Decompose() [2014-03-26 01:11:06,890] [Log ] [18 ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0 [2014-03-26 01:11:06,890] [Log ] [18 ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph [2014-03-26 01:11:06,890] [Log ] [18 ] [INFO ] - dvb:StopGraph called [2014-03-26 01:11:06,890] [Log ] [18 ] [INFO ] - tvcard:FreeAllSubChannels [2014-03-26 01:11:07,087] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:07,587] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:07,893] [Log ] [18 ] [INFO ] - dvb:StopGraph - conditionalAccess.AllowedToStopGraph = false [2014-03-26 01:11:07,894] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:11:07,894] [Log ] [18 ] [INFO ] - dvb:StopGraph called [2014-03-26 01:11:07,894] [Log ] [18 ] [INFO ] - tvcard:FreeAllSubChannels [2014-03-26 01:11:07,895] [Log ] [18 ] [INFO ] - dvb:StopGraph - conditionalAccess.AllowedToStopGraph = false [2014-03-26 01:11:07,895] [Log ] [18 ] [INFO ] - user:HTPC-Dator remove [2014-03-26 01:11:07,895] [Log ] [18 ] [DEBUG] - CardReservation.RemoveStopTicket: removed STOP reservation with id=4 [2014-03-26 01:11:07,895] [Log ] [18 ] [INFO ] - Controller:Timeshifting stopped on card:3 [2014-03-26 01:11:07,895] [Log ] [18 ] [INFO ] - RTSP: remove stream stream3.0 [2014-03-26 01:11:07,896] [Log ] [18 ] [INFO ] - Controller: epg start [2014-03-26 01:11:07,896] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:11:08,089] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:08,602] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:09,117] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:09,631] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:10,146] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:12,907] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:11:12,907] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:11:12,907] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:11:12,907] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer1.ts [2014-03-26 01:11:12,907] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer1.ts [2014-03-26 01:11:13,235] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:13,750] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:14,265] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:14,779] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:11:15,294] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD