[2014-03-26 01:06:14,808] [Log ] [4 ] [DEBUG] - Setting up EventWaitHandle with name: Global\MPTVServiceInitializedEvent [2014-03-26 01:06:16,888] [Log ] [4 ] [INFO ] - Set loglevel to: Debug [2014-03-26 01:06:16,898] [Log ] [7 ] [INFO ] - TV service: Starting [2014-03-26 01:06:16,928] [Log ] [TVService] [INFO ] - TVService v1.6.0.0 is starting up on Windows 7 ( Service Pack 1 ) [6.1.7601.65536] [2014-03-26 01:06:16,948] [Log ] [TVService] [INFO ] - Controller: Initializing TVServer [2014-03-26 01:06:16,948] [Log ] [TVService] [INFO ] - Controller: 1 init attempt [2014-03-26 01:06:16,948] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread message loop is running [2014-03-26 01:06:16,988] [Log ] [TVService] [INFO ] - C:\ProgramData\Team MediaPortal\MediaPortal TV Server\gentle.config [2014-03-26 01:06:16,988] [Log ] [TVService] [INFO ] - Controller: using MySQL database connection: Server=HTPC-Dator;Database=MpTvDb;User ID=root;Password [2014-03-26 01:06:16,988] [Log ] [TVService] [INFO ] - Controller: started at HTPC-Dator [2014-03-26 01:06:16,988] [Log ] [TVService] [INFO ] - Controller: local ip address:192.168.0.131 [2014-03-26 01:06:17,068] [Log ] [TVService] [INFO ] - Controller: server running on HTPC-Dator [2014-03-26 01:06:17,198] [Log ] [TVService] [DEBUG] - Initialise BDA device detection graph [2014-03-26 01:06:17,588] [Log ] [TVService] [INFO ] - Starting async device detection... [2014-03-26 01:06:17,598] [Log ] [TVService] [INFO ] - Controller: add device RadioWebStream Card (builtin) (builtin) [2014-03-26 01:06:17,806] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2014-03-26 01:06:17,806] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2014-03-26 01:06:17,806] [Log ] [TVService] [DEBUG] - Detecting BDA/WDM devices... [2014-03-26 01:06:17,822] [Log ] [TVService] [DEBUG] - Detect legacy AM filter devices [2014-03-26 01:06:17,837] [Log ] [TVService] [INFO ] - Detected new MediaPortal IPTV tuner MediaPortal IPTV Source Filter @device:sw:{083863F1-70DE-11D0-BD40-00A0C911CE86}\{D3DD4C59-D3A7-4B82-9727-7B9203EB67C0} [2014-03-26 01:06:17,837] [Log ] [TVService] [INFO ] - Controller: add device MediaPortal IPTV Source Filter @device:sw:{083863F1-70DE-11D0-BD40-00A0C911CE86}\{D3DD4C59-D3A7-4B82-9727-7B9203EB67C0} [2014-03-26 01:06:17,837] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2014-03-26 01:06:17,837] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2014-03-26 01:06:17,837] [Log ] [TVService] [DEBUG] - Detect AM KS crossbar devices [2014-03-26 01:06:17,837] [Log ] [TVService] [DEBUG] - Detect AM KS TV tuner devices [2014-03-26 01:06:17,853] [Log ] [TVService] [DEBUG] - Detect BDA source devices [2014-03-26 01:06:17,962] [Log ] [TVService] [INFO ] - Detected new digital BDA tuner device DTV-DVB Mantis DVBT BDA Receiver @device:pnp:\\?\pci#ven_1822&dev_4e35&subsys_00241822&rev_01#4&1542fbd&0&00f0#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{ccb6e038-21f5-4c73-8f84-65ea23b8fae1} [2014-03-26 01:06:17,962] [Log ] [TVService] [DEBUG] - check type with MS NP [2014-03-26 01:06:17,978] [Log ] [TVService] [DEBUG] - network type count = 1 [2014-03-26 01:06:17,978] [Log ] [TVService] [DEBUG] - network type 0 = 216c62df-6d7f-4e9a-8571-05f14edb766a [2014-03-26 01:06:17,978] [Log ] [TVService] [INFO ] - tuner type = DvbT [2014-03-26 01:06:17,978] [Log ] [TVService] [INFO ] - Controller: add device DTV-DVB Mantis DVBT BDA Receiver @device:pnp:\\?\pci#ven_1822&dev_4e35&subsys_00241822&rev_01#4&1542fbd&0&00f0#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{ccb6e038-21f5-4c73-8f84-65ea23b8fae1} [2014-03-26 01:06:17,978] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2014-03-26 01:06:17,978] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2014-03-26 01:06:18,102] [Log ] [TVService] [INFO ] - Controller: setup streaming [2014-03-26 01:06:18,414] [Log ] [TVService] [INFO ] - Scheduler: started [2014-03-26 01:06:18,789] [Log ] [TVService] [INFO ] - Scheduler: loaded 0 schedules [2014-03-26 01:06:18,789] [Log ] [TVService] [DEBUG] - Scheduler: thread started. [2014-03-26 01:06:18,789] [Log ] [TVService] [INFO ] - DiskManagement: started [2014-03-26 01:06:18,804] [Log ] [TVService] [INFO ] - ThumbProcessor.Start() [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings() [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings: Enable Thumbs: True [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings: Share preview: False [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings: Thumbs quality: 4 [2014-03-26 01:06:18,804] [Log ] [TVService] [INFO ] - Thumbs.LoadSettings: using highest quality thumbnail mode [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - ThumbProcessor.Start: Create thumbs folder [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - Thumbs.CreateFolders() [2014-03-26 01:06:18,804] [Log ] [TVService] [DEBUG] - ThumbProcessor.Start: Enqueued 0 recordings [2014-03-26 01:06:18,804] [Log ] [TVService] [INFO ] - Controller: setup HeartBeat Monitor [2014-03-26 01:06:18,804] [Log ] [HeartBeatMonitor] [INFO ] - Controller: Heartbeat Monitor initiated, max timeout allowed is 30 sec. [2014-03-26 01:06:18,851] [Log ] [TVService] [DEBUG] - ExecutePendingDeletions: number of pending deletions : 0 [2014-03-26 01:06:18,851] [Log ] [TVService] [INFO ] - Controller: recalculating program states [2014-03-26 01:06:18,851] [Log ] [TVService] [INFO ] - schedule.SynchProgramStatesForAll [2014-03-26 01:06:18,851] [Log ] [TVService] [INFO ] - Controller: initalized [2014-03-26 01:06:18,851] [Log ] [TVService] [INFO ] - Controller: TVServer initialized okay [2014-03-26 01:06:18,851] [Log ] [TVService] [INFO ] - TV Service: Load plugins [2014-03-26 01:06:18,867] [Log ] [TVService] [INFO ] - PluginManager: Loaded ComSkipLauncher version:1.0.2.0 author:and-81 [2014-03-26 01:06:18,867] [Log ] [TVService] [INFO ] - PluginManager: Loaded ConflictsManager version:1.0.0.1 author:Broceliande [2014-03-26 01:06:18,882] [Log ] [TVService] [INFO ] - PluginManager: Loaded PowerScheduler version:1.6.0.0 author:michael_t (based on the work of micheloe and others) [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - PluginManager: Loaded ServerBlaster version:1.1.1.0 author:joboehl with ralphy mods [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - PluginManager: Loaded TV Movie EPG import version:1.0.3.0 author:rtv [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - PluginManager: Loaded WebEPG version:1.0.0.0 author:Arion_p - James [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - PluginManager: Loaded XmlTv version:1.0.0.0 author:Frodo [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - TV Service: Plugins loaded [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - TV Service: Plugin: ComSkipLauncher disabled [2014-03-26 01:06:18,898] [Log ] [TVService] [INFO ] - TV Service: Plugin: ConflictsManager disabled [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV Service: Plugin: PowerScheduler disabled [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV Service: Plugin: ServerBlaster disabled [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV Service: Plugin: TV Movie EPG import disabled [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV Service: Plugin: WebEPG disabled [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV Service: Plugin: XmlTv disabled [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV Service: Plugins started [2014-03-26 01:06:19,179] [Log ] [TVService] [INFO ] - TV service: Started [2014-03-26 01:06:19,460] [Log ] [16 ] [DEBUG] - Detecting BDA/WDM devices... [2014-03-26 01:06:19,460] [Log ] [16 ] [DEBUG] - Detect legacy AM filter devices [2014-03-26 01:06:19,460] [Log ] [16 ] [DEBUG] - Detect AM KS crossbar devices [2014-03-26 01:06:19,460] [Log ] [16 ] [DEBUG] - Detect AM KS TV tuner devices [2014-03-26 01:06:19,460] [Log ] [16 ] [DEBUG] - Detect BDA source devices [2014-03-26 01:06:48,425] [Log ] [16 ] [DEBUG] - Detecting BDA/WDM devices... [2014-03-26 01:06:48,425] [Log ] [16 ] [DEBUG] - Detect legacy AM filter devices [2014-03-26 01:06:48,425] [Log ] [16 ] [DEBUG] - Detect AM KS crossbar devices [2014-03-26 01:06:48,425] [Log ] [16 ] [DEBUG] - Detect AM KS TV tuner devices [2014-03-26 01:06:48,425] [Log ] [16 ] [DEBUG] - Detect BDA source devices [2014-03-26 01:07:07,686] [Log ] [5 ] [DEBUG] - UserFactory setting PriorityEPG : 1 [2014-03-26 01:07:07,686] [Log ] [5 ] [DEBUG] - UserFactory setting PriorityUser : 2 [2014-03-26 01:07:07,686] [Log ] [5 ] [DEBUG] - UserFactory setting PriorityScheduler : 100 [2014-03-26 01:07:07,696] [Log ] [5 ] [INFO ] - Controller: find free card for channel TV3 STOCKHOLM [2014-03-26 01:07:07,706] [Log ] [5 ] [INFO ] - Controller: find card for channel TV3 STOCKHOLM [2014-03-26 01:07:08,062] [Log ] [5 ] [INFO ] - Controller: got 1 tuning details for TV3 STOCKHOLM [2014-03-26 01:07:08,062] [Log ] [5 ] [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:07:08,077] [Log ] [5 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-03-26 01:07:08,077] [Log ] [5 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-03-26 01:07:08,202] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel [2014-03-26 01:07:08,202] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT users: 0 [2014-03-26 01:07:08,202] [Log ] [5 ] [INFO ] - Controller: found 1 card(s) for channel [2014-03-26 01:07:08,202] [Log ] [5 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 506 msec [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT is available [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: found 1 free card(s) [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 512 msec [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: TimeShiftingWouldUseCard TV3 STOCKHOLM 69 [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: find free card for channel TV3 STOCKHOLM [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: find card for channel TV3 STOCKHOLM [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: got 1 tuning details for TV3 STOCKHOLM [2014-03-26 01:07:08,218] [Log ] [5 ] [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:07:08,218] [Log ] [5 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT users: 0 [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: found 1 card(s) for channel [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 2 msec [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT is available [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - Controller: found 1 free card(s) [2014-03-26 01:07:08,218] [Log ] [5 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 3 msec [2014-03-26 01:07:08,218] [Log ] [5 ] [DEBUG] - CiMenuSupported called cardid 3 [2014-03-26 01:07:08,218] [Log ] [5 ] [DEBUG] - CiMenuSupported card DTV-DVB Mantis DVBT BDA Receiver supported: False [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: StartTimeShifting TV3 STOCKHOLM 69 [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: epg stop [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: find free card for channel TV3 STOCKHOLM [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: find card for channel TV3 STOCKHOLM [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: got 1 tuning details for TV3 STOCKHOLM [2014-03-26 01:07:08,233] [Log ] [5 ] [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:07:08,233] [Log ] [5 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: found 1 card(s) for channel [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 6 msec [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - Controller: found 1 free card(s) [2014-03-26 01:07:08,233] [Log ] [5 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec [2014-03-26 01:07:08,249] [Log ] [5 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=1, 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:07:08,280] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT users: 0 [2014-03-26 01:07:08,280] [Log ] [5 ] [INFO ] - Controller: card:3 type:DvbT is available [2014-03-26 01:07:08,280] [Log ] [5 ] [INFO ] - Controller: try max 1 of 1 cards for timeshifting [2014-03-26 01:07:08,296] [Log ] [5 ] [DEBUG] - CardReservationBase: tvcard=3, user=HTPC-Dator, dbChannel=69, ticket=1, tunestate=Tuning, stopstate=Idle [2014-03-26 01:07:08,296] [Log ] [5 ] [DEBUG] - RemoteControl: RegisterChannel first called in Domain TVService.exe for thread with id 5 [2014-03-26 01:07:11,384] [Log ] [5 ] [INFO ] - card: CardTune 3 TV3 STOCKHOLM HTPC-Dator:3:-1 [2014-03-26 01:07:11,384] [Log ] [5 ] [INFO ] - card: Tune on card 3 to subchannel TV3 STOCKHOLM [2014-03-26 01:07:11,400] [Log ] [5 ] [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:07:11,400] [Log ] [5 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events [2014-03-26 01:07:11,400] [Log ] [5 ] [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:07:11,400] [Log ] [5 ] [INFO ] - dvbt:BuildGraph [2014-03-26 01:07:11,494] [Log ] [5 ] [INFO ] - dvb:AddNetworkProviderFilter [2014-03-26 01:07:11,494] [Log ] [5 ] [INFO ] - dvb:AddNetworkProviderFilter [2014-03-26 01:07:11,494] [Log ] [5 ] [INFO ] - dvb:Add Generic Network Provider [2014-03-26 01:07:11,494] [Log ] [5 ] [INFO ] - dvb: Add Mediaportal TsWriter filter [2014-03-26 01:07:11,556] [Log ] [5 ] [INFO ] - dvbt:CreateTuningSpace() [2014-03-26 01:07:11,572] [Log ] [5 ] [INFO ] - dvbt:found correct tuningspace MediaPortal DVBT TuningSpace [2014-03-26 01:07:11,587] [Log ] [5 ] [INFO ] - dvb:Add MPEG2 Demultiplexer filter [2014-03-26 01:07:11,618] [Log ] [5 ] [INFO ] - dvb:AddAndConnectBDABoardFilters [2014-03-26 01:07:11,634] [Log ] [5 ] [INFO ] - dvb: find bda tuner [2014-03-26 01:07:11,774] [Log ] [5 ] [INFO ] - dvb: using [Tuner]: DTV-DVB Mantis DVBT BDA Receiver [2014-03-26 01:07:11,774] [Log ] [5 ] [INFO ] - dvb: Render [Network provider]->[Tuner] OK [2014-03-26 01:07:11,774] [Log ] [5 ] [INFO ] - dvb: Setting lastFilter to Tuner filter [2014-03-26 01:07:11,774] [Log ] [5 ] [INFO ] - dvb: Find BDA receiver [2014-03-26 01:07:11,774] [Log ] [5 ] [INFO ] - dvb: match Capture by Tuner device path [2014-03-26 01:07:11,790] [Log ] [5 ] [INFO ] - dvb: -DTV-DVB Mantis DVBT Capture BDA Receiver [2014-03-26 01:07:11,790] [Log ] [5 ] [INFO ] - dvb: Render [Tuner]->[Capture] AOK [2014-03-26 01:07:11,790] [Log ] [5 ] [INFO ] - dvb: Setting lastFilter to Capture device [2014-03-26 01:07:11,790] [Log ] [5 ] [INFO ] - dvb: WinTv CI module not detected. [2014-03-26 01:07:11,806] [Log ] [5 ] [INFO ] - dvb:add Inf Tee filter [2014-03-26 01:07:11,806] [Log ] [5 ] [INFO ] - dvb: Render ...->[inftee] [2014-03-26 01:07:11,806] [Log ] [5 ] [INFO ] - dvb: Setting lastFilter to Inf Tee [2014-03-26 01:07:11,806] [Log ] [5 ] [INFO ] - dvb: Render [inftee]->[demux] [2014-03-26 01:07:11,837] [Log ] [5 ] [INFO ] - dvb: AddTransportStreamFiltersToGraph [2014-03-26 01:07:11,837] [Log ] [5 ] [INFO ] - add BDA MPEG2 Transport Information Filter filter [2014-03-26 01:07:11,837] [Log ] [5 ] [INFO ] - pinTif:name:IB Input [3/0] Direction:Input Connected:False [2014-03-26 01:07:11,837] [Log ] [5 ] [INFO ] - Connect tif and mpeg2 sections and tables [2014-03-26 01:07:11,837] [Log ] [5 ] [INFO ] - dvb:try tif:name:001 [5/0] Direction:Output Connected:False [2014-03-26 01:07:11,868] [Log ] [5 ] [INFO ] - tif connected [2014-03-26 01:07:11,868] [Log ] [5 ] [INFO ] - dvb: Render ..->[TsWriter] [2014-03-26 01:07:11,868] [Log ] [5 ] [INFO ] - dvb: Checking for hardware specific extensions [2014-03-26 01:07:11,884] [Log ] [5 ] [INFO ] - CAM is Default model [2014-03-26 01:07:11,884] [Log ] [5 ] [INFO ] - Check for KNC [2014-03-26 01:07:11,899] [Log ] [5 ] [INFO ] - KNC: IsKNC False [2014-03-26 01:07:11,899] [Log ] [5 ] [DEBUG] - KNC: Disable CI [2014-03-26 01:07:12,149] [Log ] [5 ] [DEBUG] - KNC: Disposing CI handler [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Check for Digital Everywhere [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Check for Twinhan [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CI detection finished [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CI State:C MMI State:0 [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CI status read [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CAM inserted [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CI State:C MMI State:0 [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CI status read [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan: CAM detected:True [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan card detected [2014-03-26 01:07:12,149] [Log ] [5 ] [INFO ] - Twinhan registering CI menu capabilities [2014-03-26 01:07:12,258] [Log ] [5 ] [INFO ] - dvb: GetTunerSignalStatistics() [2014-03-26 01:07:12,274] [Log ] [5 ] [INFO ] - RunGraph [2014-03-26 01:07:12,274] [Log ] [5 ] [INFO ] - dvb: RunGraph [2014-03-26 01:07:12,289] [Log ] [5 ] [INFO ] - dvb:GetNewSubChannel:0 #0 [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - TvDvbChannel ctor new subchIndex:0 [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - card: AddTuneEvent card: 3 / subch: 0 [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - dvb:Submiting tunerequest Channel:TV3 STOCKHOLM subChannel:0 [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - dvb:using existing subchannel:0 [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - dvb:Submit tunerequest size:1 new:0 [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - subch:0 OnBeforeTune [2014-03-26 01:07:12,305] [Log ] [5 ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest [2014-03-26 01:07:13,100] [Log ] [5 ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest [2014-03-26 01:07:13,100] [Log ] [5 ] [INFO ] - subch:0 OnAfterTune [2014-03-26 01:07:13,116] [Log ] [5 ] [INFO ] - RunGraph [2014-03-26 01:07:13,116] [Log ] [5 ] [INFO ] - dvb: LockInOnSignal ok [2014-03-26 01:07:13,116] [Log ] [5 ] [INFO ] - subch:0 OnGraphStart [2014-03-26 01:07:13,116] [Log ] [5 ] [INFO ] - subch:0 Graph already running - WaitForPMT [2014-03-26 01:07:13,147] [Log ] [5 ] [INFO ] - subch:0 SetupPmtGrabber:pid BB8 sid:BB8 [2014-03-26 01:07:13,147] [Log ] [5 ] [INFO ] - subch:0 set pmt grabber pmt:BB8 sid:BB8 [2014-03-26 01:07:13,147] [Log ] [5 ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time [2014-03-26 01:07:13,147] [Log ] [5 ] [DEBUG] - WaitForPMT: Waiting for PMT BB8 [2014-03-26 01:07:13,163] [Log ] [19 ] [INFO ] - subch:0 OnPMTReceived() pmt:BB8 ran:True dynamic:False [2014-03-26 01:07:13,163] [Log ] [5 ] [DEBUG] - WaitForPMT: Found PMT after 0,0156 seconds. [2014-03-26 01:07:13,163] [Log ] [5 ] [INFO ] - subch:0 SendPmt:BB8 BB8 FFFFFFFF 1 [2014-03-26 01:07:13,163] [Log ] [5 ] [INFO ] - Decode pmt [2014-03-26 01:07:13,163] [Log ] [5 ] [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:07:13,163] [Log ] [5 ] [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:07:13,163] [Log ] [5 ] [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:07:13,163] [Log ] [5 ] [INFO ] - Set descriptor data with length 6 [2014-03-26 01:07:13,163] [Log ] [5 ] [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:07:13,163] [Log ] [5 ] [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:07:13,163] [Log ] [5 ] [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:07:13,163] [Log ] [5 ] [INFO ] - Set descriptor data with length 7 [2014-03-26 01:07:13,163] [Log ] [5 ] [INFO ] - subch:0 SendPMT version:1 len:152 3000 [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - Decode pmt [2014-03-26 01:07:13,178] [Log ] [5 ] [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:07:13,178] [Log ] [5 ] [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:07:13,178] [Log ] [5 ] [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:07:13,178] [Log ] [5 ] [INFO ] - Set descriptor data with length 6 [2014-03-26 01:07:13,178] [Log ] [5 ] [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:07:13,178] [Log ] [5 ] [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:07:13,178] [Log ] [5 ] [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:07:13,178] [Log ] [5 ] [INFO ] - Set descriptor data with length 7 [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - Twinhan: Send PMT, len: 120 [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - capmt: [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0000|03 0B B8 C3 00 00 02 EB C1 00 34 01 09 0F 05 00 |__________4_____ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0010|E0 32 10 01 00 13 01 20 14 03 02 05 08 09 0F 05 |_2_____ ________ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0020|00 E0 68 10 01 00 13 01 20 14 03 02 32 08 09 0F |__h_____ ___2___ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0030|05 00 E0 81 10 01 00 13 01 20 14 03 04 0D 08 03 |_________ ______ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0040|EB C0 00 34 01 09 0F 05 00 E0 32 10 01 00 13 01 |___4______2_____ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0050|20 14 03 02 05 08 09 0F 05 00 E0 68 10 01 00 13 | __________h____ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0060|01 20 14 03 02 32 08 09 0F 05 00 E0 81 10 01 00 |_ ___2__________ [2014-03-26 01:07:13,178] [Log ] [5 ] [INFO ] - 0070|13 01 20 14 03 04 0D 08 |__ _____ [2014-03-26 01:07:14,192] [Log ] [5 ] [INFO ] - Twinhan: CAM returned ok 0x0 [2014-03-26 01:07:14,192] [Log ] [5 ] [INFO ] - subch:0 cam flags:True [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 SetMpegPidMapping [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 pid:BC1 pcr [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 pid:BB8 pmt [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 pid:BC1 video type:MPEG-2 [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 map pid:BC1 video type:MPEG-2 [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 pid:BC0 audio lang:swe type:MPEG-1 [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 map pid:BC0 audio lang:swe type:MPEG-1 [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 pid:BBE teletext type:6 [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 map pid:BBE teletext type:6 [2014-03-26 01:07:14,208] [Log ] [5 ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - subch:0 stop tif [2014-03-26 01:07:14,208] [Log ] [5 ] [DEBUG] - WaitForPmt: PMT handling took 1,0452019 seconds. [2014-03-26 01:07:14,208] [Log ] [5 ] [INFO ] - dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel [2014-03-26 01:07:14,224] [Log ] [5 ] [DEBUG] - card: Tuner locked: True [2014-03-26 01:07:14,224] [Log ] [5 ] [INFO ] - ************************************************** [2014-03-26 01:07:14,224] [Log ] [5 ] [INFO ] - ***** SIGNAL LEVEL: 43, SIGNAL QUALITY: 43 ***** [2014-03-26 01:07:14,224] [Log ] [5 ] [INFO ] - ************************************************** [2014-03-26 01:07:14,224] [Log ] [5 ] [DEBUG] - card: tuned user: HTPC-Dator subchannel: 0 [2014-03-26 01:07:14,224] [Log ] [5 ] [INFO ] - user:HTPC-Dator add [2014-03-26 01:07:14,224] [Log ] [5 ] [INFO ] - card2:HTPC-Dator 3 0 [2014-03-26 01:07:14,224] [Log ] [5 ] [INFO ] - Controller: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - Controller: epg stop [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - card: StartTimeShifting 3 C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - card: CAM enabled : True [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - subch:0 SetTimeShiftFileName:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - Set video / audio observer [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - subch:0 SetTimeShiftFileName fill in pids [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - subch:0-0 tswriter StartTimeshifting... [2014-03-26 01:07:14,239] [Log ] [5 ] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo [2014-03-26 01:07:14,270] [Log ] [19 ] [INFO ] - PID seen - type = Video [2014-03-26 01:07:14,270] [Log ] [19 ] [INFO ] - audioVideoEventHandler Video [2014-03-26 01:07:14,380] [Log ] [19 ] [INFO ] - PID seen - type = Audio [2014-03-26 01:07:14,380] [Log ] [19 ] [INFO ] - audioVideoEventHandler Audio [2014-03-26 01:07:14,380] [Log ] [5 ] [INFO ] - card: WaitForFile - video and audio are seen after 0,1404003 seconds [2014-03-26 01:07:14,551] [Log ] [5 ] [INFO ] - TimeshiftingEPG: channel TV3 STOCKHOLM is not configured for grabbing epg [2014-03-26 01:07:14,551] [Log ] [5 ] [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:07:14,551] [Log ] [5 ] [INFO ] - RTSP: start streamer [2014-03-26 01:07:14,551] [Log ] [RTSP Streaming thread] [INFO ] - RTSP: Streamer started [2014-03-26 01:07:14,551] [Log ] [5 ] [INFO ] - RTSP: add stream stream3.0 file:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:07:14,551] [Log ] [5 ] [INFO ] - Controller: StartTimeShifting started on card:3 to C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:07:14,551] [Log ] [5 ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=1, 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:07:14,551] [Log ] [5 ] [INFO ] - card: SignalTuneEvent card: 3 / subch: 0 [2014-03-26 01:07:14,551] [Log ] [5 ] [INFO ] - Controller: HTPC-Dator 3 0 [2014-03-26 01:07:14,551] [Log ] [5 ] [INFO ] - control2:HTPC-Dator 3 0 [2014-03-26 01:07:14,614] [Log ] [5 ] [DEBUG] - CiMenuSupported called cardid 3 [2014-03-26 01:07:14,614] [Log ] [5 ] [DEBUG] - CiMenuSupported card DTV-DVB Mantis DVBT BDA Receiver supported: True [2014-03-26 01:07:14,645] [Log ] [5 ] [DEBUG] - CiMenu: registered client event for callback [2014-03-26 01:07:14,645] [Log ] [5 ] [DEBUG] - TvController: EnableCiMenuHandler called [2014-03-26 01:07:14,645] [Log ] [5 ] [DEBUG] - Twinhan: registering ci callbacks [2014-03-26 01:07:14,645] [Log ] [5 ] [DEBUG] - TwinHan: Starting new CI handler thread [2014-03-26 01:07:14,645] [Log ] [5 ] [DEBUG] - TvController: SetCiMenuHandler: result True [2014-03-26 01:07:14,660] [Log ] [TwinHan CiMenuHandler] [DEBUG] - TwinHan: CI handler thread start polling status [2014-03-26 01:07:14,660] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:15,161] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:15,661] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:16,161] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:16,661] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:21,197] [Log ] [12 ] [DEBUG] - Controller: DoSetChannelStates for 47 channels [2014-03-26 01:07:21,206] [Log ] [12 ] [INFO ] - ChannelStates.GetAllTimeshiftingAndRecordingChannels took 2 msec [2014-03-26 01:07:21,337] [Log ] [12 ] [INFO ] - ChannelStates.DoSetChannelStates took 140 msec [2014-03-26 01:07:21,661] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:22,161] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:22,662] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:23,162] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:23,662] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:29,032] [Log ] [12 ] [INFO ] - Controller: StopTimeShifting 3 [2014-03-26 01:07:29,037] [Log ] [12 ] [DEBUG] - CardTuner.RequestCardStopReservation: placed reservation with id=2, user=HTPC-Dator [2014-03-26 01:07:29,139] [Log ] [12 ] [DEBUG] - CardTuner.Stop: ticket id=2, found=True [2014-03-26 01:07:29,139] [Log ] [12 ] [INFO ] - Stop cardid=3, ticket=2, tunestate=Idle, stopstate=Stopping [2014-03-26 01:07:29,141] [Log ] [12 ] [INFO ] - card DTV-DVB Mantis DVBT BDA Receiver: StopTimeShifting user:HTPC-Dator sub:0 [2014-03-26 01:07:29,141] [Log ] [12 ] [DEBUG] - card not IDLE - removing user: HTPC-Dator [2014-03-26 01:07:29,144] [Log ] [12 ] [DEBUG] - usermanagement.RemoveUser: HTPC-Dator, subch: 0 of 1, card: 3 [2014-03-26 01:07:29,146] [Log ] [12 ] [INFO ] - user:HTPC-Dator remove [2014-03-26 01:07:29,147] [Log ] [12 ] [INFO ] - subch:0-0 tswriter StopTimeshifting... [2014-03-26 01:07:29,150] [Log ] [12 ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0 [2014-03-26 01:07:29,151] [Log ] [12 ] [INFO ] - DVB subch:0 Decompose() [2014-03-26 01:07:29,152] [Log ] [12 ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0 [2014-03-26 01:07:29,152] [Log ] [12 ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph [2014-03-26 01:07:29,154] [Log ] [12 ] [INFO ] - dvb:StopGraph called [2014-03-26 01:07:29,155] [Log ] [12 ] [INFO ] - tvcard:FreeAllSubChannels [2014-03-26 01:07:29,162] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:29,257] [Log ] [12 ] [INFO ] - dvb:StopGraph - conditionalAccess.AllowedToStopGraph = false [2014-03-26 01:07:29,257] [Log ] [12 ] [INFO ] - dvb:StopGraph called [2014-03-26 01:07:29,258] [Log ] [12 ] [INFO ] - tvcard:FreeAllSubChannels [2014-03-26 01:07:29,258] [Log ] [12 ] [INFO ] - dvb:StopGraph - conditionalAccess.AllowedToStopGraph = false [2014-03-26 01:07:29,258] [Log ] [12 ] [INFO ] - user:HTPC-Dator remove [2014-03-26 01:07:29,258] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:07:29,259] [Log ] [12 ] [DEBUG] - CardReservation.RemoveStopTicket: removed STOP reservation with id=2 [2014-03-26 01:07:29,259] [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:07:29,259] [Log ] [12 ] [INFO ] - Controller:Timeshifting stopped on card:3 [2014-03-26 01:07:29,260] [Log ] [12 ] [INFO ] - RTSP: remove stream stream3.0 [2014-03-26 01:07:29,261] [Log ] [12 ] [INFO ] - Controller: epg start [2014-03-26 01:07:29,662] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:30,176] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:30,691] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:31,206] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:31,721] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:34,263] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2014-03-26 01:07:34,263] [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:07:34,263] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2014-03-26 01:07:34,263] [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:07:34,263] [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:07:34,273] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:34,777] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:35,292] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:35,806] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:36,321] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:43,076] [Log ] [12 ] [INFO ] - Controller: epg stop [2014-03-26 01:07:43,528] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:44,043] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:44,558] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:45,073] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD [2014-03-26 01:07:45,588] [Log ] [TwinHan CiMenuHandler] [INFO ] - MMI State CI_STATUS_EMPTY_OLD