[2015-07-09 14:56:20,396] [Log ] [4 ] [DEBUG] - Setting up EventWaitHandle with name: Global\MPTVServiceInitializedEvent [2015-07-09 14:56:20,412] [Log ] [4 ] [INFO ] - Global\MPTVServiceInitializedEvent was not created [2015-07-09 14:56:20,646] [Log ] [4 ] [INFO ] - Set loglevel to: Debug [2015-07-09 14:56:20,646] [Log ] [8 ] [INFO ] - TV Service: Starting [2015-07-09 14:56:20,646] [Log ] [TVService] [INFO ] - TVService v1.11.0.0 is starting up on Compatibilty Mode: Windows 8 [6.2.9200.0] [2015-07-09 14:56:20,662] [Log ] [TVService] [INFO ] - Controller: Initializing TVServer [2015-07-09 14:56:20,662] [Log ] [PowerEventThread] [DEBUG] - TV service PowerEventThread message loop is running [2015-07-09 14:56:20,662] [Log ] [TVService] [INFO ] - Controller: 1 init attempt [2015-07-09 14:56:20,677] [Log ] [TVService] [INFO ] - C:\ProgramData\Team MediaPortal\MediaPortal TV Server\gentle.config [2015-07-09 14:56:20,677] [Log ] [TVService] [INFO ] - Controller: using MySQL database connection: Server=MediaCentre;Database=MpTvDb;User ID=root;Password [2015-07-09 14:56:20,677] [Log ] [TVService] [INFO ] - Controller: started at MediaCentre [2015-07-09 14:56:20,677] [Log ] [TVService] [INFO ] - Controller: local ip address:192.168.1.250 [2015-07-09 14:56:20,740] [Log ] [TVService] [INFO ] - Controller: server running on MediaCentre [2015-07-09 14:56:20,755] [Log ] [TVService] [DEBUG] - Initialise BDA device detection graph [2015-07-09 14:56:20,802] [Log ] [TVService] [INFO ] - Starting async device detection... [2015-07-09 14:56:20,818] [Log ] [TVService] [INFO ] - Controller: add device RadioWebStream Card (builtin) (builtin) [2015-07-09 14:56:21,037] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2015-07-09 14:56:21,037] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2015-07-09 14:56:21,037] [Log ] [TVService] [DEBUG] - Detecting BDA/WDM devices... [2015-07-09 14:56:21,052] [Log ] [TVService] [DEBUG] - Detect legacy AM filter devices [2015-07-09 14:56:21,068] [Log ] [TVService] [INFO ] - Detected new MediaPortal IPTV tuner MediaPortal IPTV Source Filter @device:sw:{083863F1-70DE-11D0-BD40-00A0C911CE86}\{D3DD4C59-D3A7-4B82-9727-7B9203EB67C0} [2015-07-09 14:56:21,068] [Log ] [TVService] [INFO ] - Controller: add device MediaPortal IPTV Source Filter @device:sw:{083863F1-70DE-11D0-BD40-00A0C911CE86}\{D3DD4C59-D3A7-4B82-9727-7B9203EB67C0} [2015-07-09 14:56:21,068] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2015-07-09 14:56:21,068] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2015-07-09 14:56:21,084] [Log ] [TVService] [DEBUG] - Detect AM KS crossbar devices [2015-07-09 14:56:21,084] [Log ] [TVService] [DEBUG] - Detect AM KS TV tuner devices [2015-07-09 14:56:21,084] [Log ] [TVService] [DEBUG] - Detect BDA source devices [2015-07-09 14:56:21,099] [Log ] [TVService] [INFO ] - Detected new digital BDA tuner device TBS 6982 DVBS/S2 Tuner A @device:pnp:\\?\pci#ven_1131&dev_7160&subsys_00026982&rev_03#4&3ad12dd8&0&00e1#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9bb4f27f-515c-43ca-8e7d-f07c00080200} [2015-07-09 14:56:21,099] [Log ] [TVService] [DEBUG] - check type with MS NP [2015-07-09 14:56:21,115] [Log ] [TVService] [DEBUG] - network type count = 1 [2015-07-09 14:56:21,115] [Log ] [TVService] [DEBUG] - network type 0 = fa4b375a-45b4-4d45-8440-263957b11623 [2015-07-09 14:56:21,115] [Log ] [TVService] [INFO ] - tuner type = DvbS [2015-07-09 14:56:21,115] [Log ] [TVService] [INFO ] - Controller: add device TBS 6982 DVBS/S2 Tuner A @device:pnp:\\?\pci#ven_1131&dev_7160&subsys_00026982&rev_03#4&3ad12dd8&0&00e1#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9bb4f27f-515c-43ca-8e7d-f07c00080200} [2015-07-09 14:56:21,130] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2015-07-09 14:56:21,130] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2015-07-09 14:56:21,130] [Log ] [TVService] [INFO ] - Detected new digital BDA tuner device TBS 6982 DVBS/S2 Tuner B @device:pnp:\\?\pci#ven_1131&dev_7160&subsys_00026982&rev_03#4&3ad12dd8&0&00e1#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9bb4f27f-515c-43ca-8e7d-f07c00080201} [2015-07-09 14:56:21,130] [Log ] [TVService] [DEBUG] - check type with MS NP [2015-07-09 14:56:21,146] [Log ] [TVService] [DEBUG] - network type count = 1 [2015-07-09 14:56:21,146] [Log ] [TVService] [DEBUG] - network type 0 = fa4b375a-45b4-4d45-8440-263957b11623 [2015-07-09 14:56:21,162] [Log ] [TVService] [INFO ] - tuner type = DvbS [2015-07-09 14:56:21,162] [Log ] [TVService] [INFO ] - Controller: add device TBS 6982 DVBS/S2 Tuner B @device:pnp:\\?\pci#ven_1131&dev_7160&subsys_00026982&rev_03#4&3ad12dd8&0&00e1#{71985f48-1ca1-11d3-9cc8-00c04f7971e0}\{9bb4f27f-515c-43ca-8e7d-f07c00080201} [2015-07-09 14:56:21,162] [Log ] [TVService] [INFO ] - Controller: preloading device [2015-07-09 14:56:21,162] [Log ] [TVService] [INFO ] - dvbs:BuildGraph [2015-07-09 14:56:21,177] [Log ] [TVService] [INFO ] - dvb:AddNetworkProviderFilter [2015-07-09 14:56:21,177] [Log ] [TVService] [INFO ] - dvb:AddNetworkProviderFilter [2015-07-09 14:56:21,177] [Log ] [TVService] [INFO ] - dvb:Add Generic Network Provider [2015-07-09 14:56:21,177] [Log ] [TVService] [INFO ] - dvb: Add Mediaportal TsWriter filter [2015-07-09 14:56:21,193] [Log ] [TVService] [INFO ] - dvbs:CreateTuningSpace() [2015-07-09 14:56:21,193] [Log ] [TVService] [INFO ] - dvbs:found correct tuningspace MediaPortal DVBS TuningSpace [2015-07-09 14:56:21,209] [Log ] [TVService] [INFO ] - dvb:Add MPEG2 Demultiplexer filter [2015-07-09 14:56:21,209] [Log ] [TVService] [INFO ] - dvb:AddAndConnectBDABoardFilters [2015-07-09 14:56:21,209] [Log ] [TVService] [INFO ] - dvb: find bda tuner [2015-07-09 14:56:21,224] [Log ] [TVService] [INFO ] - dvb: using [Tuner]: TBS 6982 DVBS/S2 Tuner B [2015-07-09 14:56:21,240] [Log ] [TVService] [INFO ] - dvb: Render [Network provider]->[Tuner] OK [2015-07-09 14:56:21,240] [Log ] [TVService] [INFO ] - dvb: Setting lastFilter to Tuner filter [2015-07-09 14:56:21,240] [Log ] [TVService] [INFO ] - dvb: Find BDA receiver [2015-07-09 14:56:21,255] [Log ] [TVService] [INFO ] - dvb: match Capture by Tuner device path [2015-07-09 14:56:21,255] [Log ] [TVService] [INFO ] - dvb: -TBS 6982 BDA Digital Capture A DVBS [2015-07-09 14:56:21,271] [Log ] [TVService] [INFO ] - dvb: Looking for another bda receiver... [2015-07-09 14:56:21,380] [Log ] [TVService] [INFO ] - dvb: -TBS 6982 BDA Digital Capture B DVBS [2015-07-09 14:56:21,380] [Log ] [TVService] [INFO ] - dvb: Render [Tuner]->[Capture] AOK [2015-07-09 14:56:21,380] [Log ] [TVService] [INFO ] - dvb: Setting lastFilter to Capture device [2015-07-09 14:56:21,396] [Log ] [TVService] [INFO ] - dvb: WinTv CI module not detected. [2015-07-09 14:56:21,412] [Log ] [TVService] [INFO ] - dvb:add Inf Tee filter [2015-07-09 14:56:21,412] [Log ] [TVService] [INFO ] - dvb: Render ...->[inftee] [2015-07-09 14:56:21,412] [Log ] [TVService] [INFO ] - dvb: Setting lastFilter to Inf Tee [2015-07-09 14:56:21,412] [Log ] [TVService] [INFO ] - dvb: Render [inftee]->[demux] [2015-07-09 14:56:21,427] [Log ] [TVService] [INFO ] - dvb: AddTransportStreamFiltersToGraph [2015-07-09 14:56:21,427] [Log ] [TVService] [INFO ] - add BDA MPEG2 Transport Information Filter filter [2015-07-09 14:56:21,427] [Log ] [TVService] [INFO ] - pinTif:name:IB Input [3/0] Direction:Input Connected:False [2015-07-09 14:56:21,427] [Log ] [TVService] [INFO ] - Connect tif and mpeg2 sections and tables [2015-07-09 14:56:21,443] [Log ] [TVService] [INFO ] - dvb:try tif:name:001 [5/0] Direction:Output Connected:False [2015-07-09 14:56:21,443] [Log ] [TVService] [INFO ] - tif connected [2015-07-09 14:56:21,443] [Log ] [TVService] [INFO ] - dvb: Render ..->[TsWriter] [2015-07-09 14:56:21,443] [Log ] [TVService] [INFO ] - dvb: Checking for hardware specific extensions [2015-07-09 14:56:21,459] [Log ] [TVService] [INFO ] - CAM is Default model [2015-07-09 14:56:21,459] [Log ] [TVService] [INFO ] - Check for KNC [2015-07-09 14:56:21,459] [Log ] [TVService] [INFO ] - KNC: IsKNC False [2015-07-09 14:56:21,459] [Log ] [TVService] [DEBUG] - KNC: Disable CI [2015-07-09 14:56:21,474] [Log ] [TVService] [DEBUG] - KNC: Disposing CI handler [2015-07-09 14:56:21,474] [Log ] [TVService] [INFO ] - Check for Digital Everywhere [2015-07-09 14:56:21,474] [Log ] [TVService] [INFO ] - Check for Twinhan [2015-07-09 14:56:21,490] [Log ] [TVService] [INFO ] - Twinhan: CI detection finished [2015-07-09 14:56:21,490] [Log ] [TVService] [INFO ] - Check for TechnoTrend [2015-07-09 14:56:21,490] [Log ] [TVService] [DEBUG] - TechnoTrend: Closing hardware [2015-07-09 14:56:21,490] [Log ] [TVService] [INFO ] - Check for Hauppauge [2015-07-09 14:56:21,490] [Log ] [TVService] [DEBUG] - Hauppauge: DVB-S card NOT found! [2015-07-09 14:56:21,490] [Log ] [TVService] [INFO ] - Check for ProfRed [2015-07-09 14:56:21,505] [Log ] [TVService] [INFO ] - Check for TeVii [2015-07-09 14:56:21,521] [Log ] [TVService] [INFO ] - GenericBDAS:tuner pin not found! [2015-07-09 14:56:21,521] [Log ] [TVService] [INFO ] - Check for Conexant based card [2015-07-09 14:56:21,521] [Log ] [TVService] [DEBUG] - Conexant BDA: DVB-S card found! [2015-07-09 14:56:21,521] [Log ] [TVService] [INFO ] - Conexant BDA card detected [2015-07-09 14:56:21,521] [Log ] [TVService] [INFO ] - Check for Hauppauge WinTV CI [2015-07-09 14:56:21,568] [Log ] [TVService] [INFO ] - dvb: GetTunerSignalStatistics() [2015-07-09 14:56:21,568] [Log ] [TVService] [DEBUG] - Controller: creating standard handler [2015-07-09 14:56:21,584] [Log ] [TVService] [DEBUG] - Controller: current timeshifting folder is "C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer" [2015-07-09 14:56:21,615] [Log ] [TVService] [INFO ] - Controller: setup streaming [2015-07-09 14:56:21,787] [Log ] [TVService] [INFO ] - Scheduler: started [2015-07-09 14:56:22,224] [Log ] [TVService] [INFO ] - Scheduler: loaded 0 schedules [2015-07-09 14:56:22,224] [Log ] [TVService] [DEBUG] - Scheduler: thread started. [2015-07-09 14:56:22,224] [Log ] [TVService] [INFO ] - DiskManagement: started [2015-07-09 14:56:22,224] [Log ] [TVService] [INFO ] - ThumbProcessor.Start() [2015-07-09 14:56:22,240] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings() [2015-07-09 14:56:22,302] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings: Enable Thumbs: True [2015-07-09 14:56:22,302] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings: Share preview: False [2015-07-09 14:56:22,302] [Log ] [TVService] [DEBUG] - Thumbs.LoadSettings: Thumbs quality: 4 [2015-07-09 14:56:22,318] [Log ] [TVService] [INFO ] - Thumbs.LoadSettings: using highest quality thumbnail mode [2015-07-09 14:56:22,318] [Log ] [TVService] [DEBUG] - ThumbProcessor.Start: Create thumbs folder [2015-07-09 14:56:22,318] [Log ] [TVService] [DEBUG] - Thumbs.CreateFolders() [2015-07-09 14:56:22,318] [Log ] [TVService] [DEBUG] - ThumbProcessor.Start: Enqueued 0 recordings [2015-07-09 14:56:22,334] [Log ] [TVService] [INFO ] - Controller: setup HeartBeat Monitor [2015-07-09 14:56:22,334] [Log ] [HeartBeatMonitor] [INFO ] - Controller: Heartbeat Monitor initiated, max timeout allowed is 30 sec. [2015-07-09 14:56:22,365] [Log ] [TVService] [DEBUG] - ExecutePendingDeletions: number of pending deletions : 0 [2015-07-09 14:56:22,365] [Log ] [TVService] [INFO ] - Controller: recalculating program states [2015-07-09 14:56:22,380] [Log ] [TVService] [INFO ] - schedule.SynchProgramStatesForAll [2015-07-09 14:56:22,380] [Log ] [TVService] [INFO ] - Controller: initalized [2015-07-09 14:56:22,380] [Log ] [TVService] [INFO ] - Controller: TVServer initialized okay [2015-07-09 14:56:22,380] [Log ] [TVService] [INFO ] - TV Service: Load plugins [2015-07-09 14:56:22,396] [Log ] [TVService] [INFO ] - PluginManager: Loaded ComSkipLauncher version:1.0.2.0 author:and-81 [2015-07-09 14:56:22,396] [Log ] [TVService] [INFO ] - PluginManager: Loaded ConflictsManager version:1.0.0.1 author:Broceliande [2015-07-09 14:56:22,412] [Log ] [TVService] [INFO ] - PluginManager: Loaded PowerScheduler version:1.11.0.0 author:michael_t (based on the work of micheloe and others) [2015-07-09 14:56:22,427] [Log ] [TVService] [INFO ] - PluginManager: Loaded ServerBlaster version:1.1.1.0 author:joboehl with ralphy mods [2015-07-09 14:56:22,443] [Log ] [TVService] [INFO ] - PluginManager: Loaded Sky IT Grabber version:1.4.0.4 author:DJBlu [2015-07-09 14:56:22,443] [Log ] [TVService] [INFO ] - PluginManager: Loaded Sky NZ Grabber version:1.4.0.5 author:DJBlu [2015-07-09 14:56:22,443] [Log ] [TVService] [INFO ] - PluginManager: Loaded Sky UK Grabber version:1.4.0.6 author:DJBlu [2015-07-09 14:56:22,459] [Log ] [TVService] [INFO ] - PluginManager: Loaded TV Movie EPG import version:1.0.3.0 author:rtv [2015-07-09 14:56:22,459] [Log ] [TVService] [INFO ] - PluginManager: Loaded WebEPG version:1.0.0.0 author:Arion_p - James [2015-07-09 14:56:22,474] [Log ] [TVService] [INFO ] - PluginManager: Loaded XmlTv version:1.0.0.0 author:Frodo [2015-07-09 14:56:22,474] [Log ] [TVService] [INFO ] - TV Service: Plugins loaded [2015-07-09 14:56:22,474] [Log ] [TVService] [INFO ] - TV Service: Plugin: ComSkipLauncher disabled [2015-07-09 14:56:22,474] [Log ] [TVService] [INFO ] - TV Service: Plugin: ConflictsManager disabled [2015-07-09 14:56:22,505] [Log ] [TVService] [INFO ] - TV Service: Plugin: PowerScheduler disabled [2015-07-09 14:56:22,505] [Log ] [TVService] [INFO ] - TV Service: Plugin: ServerBlaster disabled [2015-07-09 14:56:22,521] [Log ] [TVService] [INFO ] - TV Service: Plugin: Sky IT Grabber disabled [2015-07-09 14:56:22,521] [Log ] [TVService] [INFO ] - TV Service: Plugin: Sky NZ Grabber disabled [2015-07-09 14:56:22,521] [Log ] [TVService] [INFO ] - TV Service: Plugin: Sky UK Grabber started [2015-07-09 14:56:22,568] [Log ] [TVService] [INFO ] - TV Service: Plugin: TV Movie EPG import disabled [2015-07-09 14:56:22,584] [Log ] [TVService] [INFO ] - TV Service: Plugin: WebEPG disabled [2015-07-09 14:56:22,584] [Log ] [TVService] [INFO ] - TV Service: Plugin: XmlTv disabled [2015-07-09 14:56:22,584] [Log ] [TVService] [INFO ] - TV Service: Plugins started [2015-07-09 14:56:22,584] [Log ] [TVService] [INFO ] - TV Service: Started [2015-07-09 14:56:23,787] [Log ] [12 ] [DEBUG] - RemoteControl: RegisterChannel first called in Domain TVService.exe for thread with id 12 [2015-07-09 14:56:26,896] [Log ] [12 ] [INFO ] - Controller: epg stop [2015-07-09 14:56:26,927] [Log ] [12 ] [INFO ] - Controller: epg stop [2015-07-09 14:56:28,287] [Log ] [12 ] [DEBUG] - UserFactory setting PriorityEPG : 1 [2015-07-09 14:56:28,302] [Log ] [12 ] [DEBUG] - UserFactory setting PriorityUser : 2 [2015-07-09 14:56:28,302] [Log ] [12 ] [DEBUG] - UserFactory setting PriorityScheduler : 100 [2015-07-09 14:56:28,302] [Log ] [12 ] [INFO ] - Controller: StartTimeShifting Sky UK Grabber 20 [2015-07-09 14:56:28,302] [Log ] [12 ] [INFO ] - Controller: epg stop [2015-07-09 14:56:28,318] [Log ] [12 ] [INFO ] - Controller: find free card for channel Sky UK Grabber [2015-07-09 14:56:28,318] [Log ] [12 ] [INFO ] - Controller: find card for channel Sky UK Grabber [2015-07-09 14:56:28,677] [Log ] [12 ] [INFO ] - Controller: got 1 tuning details for Sky UK Grabber [2015-07-09 14:56:28,677] [Log ] [12 ] [INFO ] - Controller: channel #1 DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 14:56:28,677] [Log ] [12 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2015-07-09 14:56:28,693] [Log ] [12 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2015-07-09 14:56:28,693] [Log ] [12 ] [INFO ] - Controller: card:3 type:DvbS is disabled [2015-07-09 14:56:28,755] [Log ] [12 ] [INFO ] - Controller: card:4 type:DvbS can tune to channel [2015-07-09 14:56:28,771] [Log ] [12 ] [INFO ] - Controller: found 1 card(s) for channel [2015-07-09 14:56:28,771] [Log ] [12 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 449 msec [2015-07-09 14:56:28,771] [Log ] [12 ] [INFO ] - Controller: found 1 free card(s) [2015-07-09 14:56:28,771] [Log ] [12 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 460 msec [2015-07-09 14:56:28,787] [Log ] [12 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=1, tuningdetails=DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 14:56:28,802] [Log ] [12 ] [INFO ] - Controller: card:4 type:DvbS users: 0 [2015-07-09 14:56:28,802] [Log ] [12 ] [INFO ] - Controller: card:4 type:DvbS is available [2015-07-09 14:56:28,802] [Log ] [12 ] [INFO ] - Controller: try max 1 of 1 cards for timeshifting [2015-07-09 14:56:28,818] [Log ] [12 ] [DEBUG] - CardReservationBase: tvcard=4, user=Custom_20.ts, dbChannel=20, ticket=1, tunestate=Tuning, stopstate=Idle [2015-07-09 14:56:28,818] [Log ] [12 ] [INFO ] - card: CardTune 4 Sky UK Grabber Custom_20.ts:4:-1 [2015-07-09 14:56:28,818] [Log ] [12 ] [INFO ] - card: Tune on card 4 to subchannel Sky UK Grabber [2015-07-09 14:56:28,834] [Log ] [12 ] [DEBUG] - card: user: Custom_20.ts:4:-1 tune DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 14:56:28,834] [Log ] [12 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events [2015-07-09 14:56:28,849] [Log ] [12 ] [INFO ] - dvbs: Tune:DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 14:56:28,849] [Log ] [12 ] [INFO ] - dvbs: Tune:DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 14:56:28,849] [Log ] [12 ] [INFO ] - dvb:GetNewSubChannel:0 #0 [2015-07-09 14:56:28,849] [Log ] [12 ] [INFO ] - TvDvbChannel ctor new subchIndex:0 [2015-07-09 14:56:28,865] [Log ] [12 ] [INFO ] - card: AddTuneEvent card: 4 / subch: 0 [2015-07-09 14:56:28,865] [Log ] [12 ] [INFO ] - LNB low:9750 hi:10600 switch:11700 [2015-07-09 14:56:28,865] [Log ] [12 ] [INFO ] - dvbs:channel modulation is set to ModNotSet [2015-07-09 14:56:28,865] [Log ] [12 ] [INFO ] - dvbs:channel FECRate is set to RateNotSet [2015-07-09 14:56:28,990] [Log ] [12 ] [INFO ] - dvb:Submiting tunerequest Channel:Sky UK Grabber subChannel:0 [2015-07-09 14:56:28,990] [Log ] [12 ] [INFO ] - dvb:using existing subchannel:0 [2015-07-09 14:56:28,990] [Log ] [12 ] [INFO ] - dvb:Submit tunerequest size:1 new:0 [2015-07-09 14:56:28,990] [Log ] [12 ] [INFO ] - subch:0 OnBeforeTune [2015-07-09 14:56:29,005] [Log ] [12 ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest [2015-07-09 14:56:29,005] [Log ] [12 ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest [2015-07-09 14:56:29,005] [Log ] [12 ] [INFO ] - subch:0 OnAfterTune [2015-07-09 14:56:29,021] [Log ] [12 ] [INFO ] - RunGraph [2015-07-09 14:56:29,021] [Log ] [12 ] [INFO ] - subch:0 OnGraphStart [2015-07-09 14:56:29,021] [Log ] [12 ] [INFO ] - dvb: RunGraph [2015-07-09 14:56:31,974] [Log ] [12 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 14:56:32,006] [Log ] [12 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 14:56:32,037] [Log ] [12 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 14:56:32,068] [Log ] [12 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 14:56:32,099] [Log ] [12 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 14:56:34,006] [Log ] [12 ] [INFO ] - dvb: LockInOnSignal could not lock onto channel - no signal or bad signal [2015-07-09 14:56:34,006] [Log ] [12 ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0 [2015-07-09 14:56:34,006] [Log ] [12 ] [INFO ] - DVB subch:0 Decompose() [2015-07-09 14:56:34,006] [Log ] [12 ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0 [2015-07-09 14:56:34,021] [Log ] [12 ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph [2015-07-09 14:56:34,021] [Log ] [12 ] [INFO ] - dvb:StopGraph called [2015-07-09 14:56:34,021] [Log ] [12 ] [INFO ] - tvcard:FreeAllSubChannels [2015-07-09 14:56:34,021] [Log ] [12 ] [INFO ] - dvb:StopGraph [2015-07-09 14:56:34,115] [Log ] [12 ] [INFO ] - debug: IMediaControl stopped! hr = 0x0 :) [2015-07-09 14:56:34,115] [Log ] [12 ] [INFO ] - card2:Custom_20.ts 4 -1 [2015-07-09 14:56:34,115] [Log ] [12 ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=1, tuningdetails=DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 14:56:34,115] [Log ] [12 ] [INFO ] - card: SignalTuneEvent card: 4 / subch: 0 [2015-07-09 14:56:34,115] [Log ] [12 ] [INFO ] - Controller: Custom_20.ts 4 -1 [2015-07-09 14:56:34,130] [Log ] [12 ] [INFO ] - Controller: Timeshifting failed, no more cards available. [2015-07-09 14:56:34,130] [Log ] [12 ] [INFO ] - Controller: epg start [2015-07-09 15:26:22,572] [Log ] [36 ] [INFO ] - Sky Plugin : Sky Channel and EPG Grabber initialised [2015-07-09 15:26:22,603] [Log ] [19 ] [INFO ] - Sky Plugin : Huffman Loaded [2015-07-09 15:26:22,619] [Log ] [19 ] [INFO ] - Sky Plugin : Grabbing Data [2015-07-09 15:26:22,619] [Log ] [19 ] [INFO ] - Sky Plugin : Throttler enabled tripling grab time to ensure all data is collected [2015-07-09 15:26:22,619] [Log ] [19 ] [INFO ] - Sky Plugin : Grabber set to grab 180 seconds of data [2015-07-09 15:26:22,635] [Log ] [40 ] [INFO ] - Controller: StartTimeShifting Sky UK Grabber 20 [2015-07-09 15:26:22,635] [Log ] [40 ] [INFO ] - Controller: epg stop [2015-07-09 15:26:22,635] [Log ] [40 ] [INFO ] - Controller: find free card for channel Sky UK Grabber [2015-07-09 15:26:22,635] [Log ] [40 ] [INFO ] - Controller: find card for channel Sky UK Grabber [2015-07-09 15:26:22,650] [Log ] [40 ] [INFO ] - Controller: got 1 tuning details for Sky UK Grabber [2015-07-09 15:26:22,650] [Log ] [40 ] [INFO ] - Controller: channel #1 DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 15:26:22,650] [Log ] [40 ] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2015-07-09 15:26:22,650] [Log ] [40 ] [INFO ] - Controller: card:2 type:DvbIP is disabled [2015-07-09 15:26:22,650] [Log ] [40 ] [INFO ] - Controller: card:3 type:DvbS is disabled [2015-07-09 15:26:22,666] [Log ] [40 ] [INFO ] - Controller: card:4 type:DvbS can tune to channel [2015-07-09 15:26:22,666] [Log ] [40 ] [INFO ] - Controller: found 1 card(s) for channel [2015-07-09 15:26:22,666] [Log ] [40 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 27 msec [2015-07-09 15:26:22,666] [Log ] [40 ] [INFO ] - Controller: found 1 free card(s) [2015-07-09 15:26:22,666] [Log ] [40 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 36 msec [2015-07-09 15:26:22,682] [Log ] [40 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=2, tuningdetails=DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 15:26:22,682] [Log ] [40 ] [INFO ] - Controller: card:4 type:DvbS users: 0 [2015-07-09 15:26:22,682] [Log ] [40 ] [INFO ] - Controller: card:4 type:DvbS is available [2015-07-09 15:26:22,682] [Log ] [40 ] [INFO ] - Controller: try max 1 of 1 cards for timeshifting [2015-07-09 15:26:22,682] [Log ] [40 ] [DEBUG] - CardReservationBase: tvcard=4, user=Custom_20.ts, dbChannel=20, ticket=2, tunestate=Tuning, stopstate=Idle [2015-07-09 15:26:22,697] [Log ] [40 ] [INFO ] - card: CardTune 4 Sky UK Grabber Custom_20.ts:4:-1 [2015-07-09 15:26:22,697] [Log ] [40 ] [INFO ] - card: Tune on card 4 to subchannel Sky UK Grabber [2015-07-09 15:26:22,697] [Log ] [40 ] [DEBUG] - card: user: Custom_20.ts:4:-1 tune DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 15:26:22,713] [Log ] [40 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events [2015-07-09 15:26:22,713] [Log ] [40 ] [INFO ] - dvbs: Tune:DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 15:26:22,713] [Log ] [40 ] [INFO ] - dvbs: Tune:DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 15:26:22,713] [Log ] [40 ] [INFO ] - dvb:GetNewSubChannel:0 #0 [2015-07-09 15:26:22,713] [Log ] [40 ] [INFO ] - TvDvbChannel ctor new subchIndex:0 [2015-07-09 15:26:22,728] [Log ] [40 ] [INFO ] - card: AddTuneEvent card: 4 / subch: 0 [2015-07-09 15:26:22,728] [Log ] [40 ] [INFO ] - LNB low:9750 hi:10600 switch:11700 [2015-07-09 15:26:22,728] [Log ] [40 ] [INFO ] - dvbs:channel modulation is set to ModNotSet [2015-07-09 15:26:22,728] [Log ] [40 ] [INFO ] - dvbs:channel FECRate is set to RateNotSet [2015-07-09 15:26:22,853] [Log ] [40 ] [INFO ] - dvb:Submiting tunerequest Channel:Sky UK Grabber subChannel:0 [2015-07-09 15:26:22,853] [Log ] [40 ] [INFO ] - dvb:using existing subchannel:0 [2015-07-09 15:26:22,853] [Log ] [40 ] [INFO ] - dvb:Submit tunerequest size:1 new:0 [2015-07-09 15:26:22,853] [Log ] [40 ] [INFO ] - subch:0 OnBeforeTune [2015-07-09 15:26:22,869] [Log ] [40 ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest [2015-07-09 15:26:22,869] [Log ] [40 ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest [2015-07-09 15:26:22,869] [Log ] [40 ] [INFO ] - subch:0 OnAfterTune [2015-07-09 15:26:22,869] [Log ] [40 ] [INFO ] - RunGraph [2015-07-09 15:26:22,885] [Log ] [40 ] [INFO ] - subch:0 OnGraphStart [2015-07-09 15:26:22,885] [Log ] [40 ] [INFO ] - dvb: RunGraph [2015-07-09 15:26:25,697] [Log ] [40 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 15:26:25,744] [Log ] [40 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 15:26:25,775] [Log ] [40 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 15:26:25,807] [Log ] [40 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 15:26:25,838] [Log ] [40 ] [INFO ] - dvb: LockInOnSignal waiting 20ms [2015-07-09 15:26:27,744] [Log ] [40 ] [INFO ] - dvb: LockInOnSignal could not lock onto channel - no signal or bad signal [2015-07-09 15:26:27,744] [Log ] [40 ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0 [2015-07-09 15:26:27,744] [Log ] [40 ] [INFO ] - DVB subch:0 Decompose() [2015-07-09 15:26:27,744] [Log ] [40 ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0 [2015-07-09 15:26:27,760] [Log ] [40 ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph [2015-07-09 15:26:27,760] [Log ] [40 ] [INFO ] - dvb:StopGraph called [2015-07-09 15:26:27,760] [Log ] [40 ] [INFO ] - tvcard:FreeAllSubChannels [2015-07-09 15:26:27,760] [Log ] [40 ] [INFO ] - dvb:StopGraph [2015-07-09 15:26:27,853] [Log ] [40 ] [INFO ] - debug: IMediaControl stopped! hr = 0x0 :) [2015-07-09 15:26:27,853] [Log ] [40 ] [INFO ] - card2:Custom_20.ts 4 -1 [2015-07-09 15:26:27,853] [Log ] [40 ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=2, tuningdetails=DVBS:radio:DJBlu Sky UK Grabber Freq:11778000 ONID:2 TSID:2004 SID:4152 PMT:0x0 FTA:True LCN:10000 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:RateNotSet DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet [2015-07-09 15:26:27,853] [Log ] [40 ] [INFO ] - card: SignalTuneEvent card: 4 / subch: 0 [2015-07-09 15:26:27,869] [Log ] [40 ] [INFO ] - Controller: Custom_20.ts 4 -1 [2015-07-09 15:26:27,869] [Log ] [40 ] [INFO ] - Controller: Timeshifting failed, no more cards available. [2015-07-09 15:26:27,869] [Log ] [40 ] [INFO ] - Controller: epg start [2015-07-09 15:26:27,869] [Log ] [32 ] [INFO ] - Sky Plugin : Error Occured:- NoSignalDetected