Tswriter (2 Viewers)

[Normann]

At this session only the channels CNN and Bloomberg functioned. I had another option in the "Watchdog" ticked, finger crossed.

 

[Owlsroost]

I've just spotted the problem with the logging - it's set to 'error' logging only:

[2018-11-24 08:58:03,624] [Log    ] [4        ] [INFO ] - Set loglevel to: Error

Can you change the log level to 'debug' please (in TV Server config app) and post a new set of logs - sorry, I should have noticed that earlier...you should get lots of logging in 'TVService.log' now.

 

[Normann]

Thank you for your patience. Now let us see if these logs are ok.

 

[Owlsroost]

Logs are OK - there are 2 failures to tune in the logs:

[2018-11-24 15:14:11,302] [Log    ] [9        ] [INFO ] - dvbs: Tune:DVBS:tv:SES ASTRA BBC World News Europe HD Freq:11229000 ONID:1 TSID:1002 SID:5001 PMT:0xD4 FTA:True LCN:10000 SymbolRate:22000 Modulation:Mod8Psk Polarisation:LinearV InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet
[2018-11-24 15:14:11,305] [Log    ] [9        ] [INFO ] - dvbs:  Tune:DVBS:tv:SES ASTRA BBC World News Europe HD Freq:11229000 ONID:1 TSID:1002 SID:5001 PMT:0xD4 FTA:True LCN:10000 SymbolRate:22000 Modulation:Mod8Psk Polarisation:LinearV InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet
[2018-11-24 15:14:11,308] [Log    ] [9        ] [INFO ] - LNB low:9750 hi:10600 switch:11700
[2018-11-24 15:14:11,311] [Log    ] [9        ] [INFO ] - dvbs:channel modulation is set to Mod8Psk
[2018-11-24 15:14:11,314] [Log    ] [9        ] [INFO ] - dvbs:channel FECRate is set to Rate2_3
[2018-11-24 15:14:11,316] [Log    ] [9        ] [INFO ] - GenericBDAS:  Level1AA - SendDiSEqCCommand(0x00)
[2018-11-24 15:14:11,319] [Log    ] [9        ] [INFO ] - GenericBDAS:  SendDiSEqC Command 0
[2018-11-24 15:14:11,322] [Log    ] [9        ] [INFO ] - GenericBDAS:  put_Range:0 success:0
[2018-11-24 15:14:11,325] [Log    ] [9        ] [INFO ] - GenericBDAS:  CommitChanges() Succeeded
[2018-11-24 15:14:11,429] [Log    ] [9        ] [INFO ] - dvb:Submiting tunerequest Channel:BBC World News Europe HD subChannel:0
[2018-11-24 15:14:11,438] [Log    ] [9        ] [INFO ] - dvb:using existing subchannel:0
[2018-11-24 15:14:11,446] [Log    ] [9        ] [INFO ] - dvb:Submit tunerequest size:1 new:0
[2018-11-24 15:14:11,455] [Log    ] [9        ] [INFO ] - subch:0 OnBeforeTune
[2018-11-24 15:14:11,464] [Log    ] [9        ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest
[2018-11-24 15:14:18,948] [Log    ] [9        ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest
[2018-11-24 15:14:18,958] [Log    ] [9        ] [INFO ] - subch:0 OnAfterTune
[2018-11-24 15:14:18,966] [Log    ] [9        ] [INFO ] - RunGraph
[2018-11-24 15:14:18,988] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:14:19,029] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:14:19,072] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:14:19,113] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:14:19,153] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:14:49,007] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal could not lock onto channel - no signal or bad signal
[2018-11-24 15:14:49,016] [Log    ] [9        ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
[2018-11-24 15:14:49,025] [Log    ] [9        ] [INFO ] - tvcard:FreeSubChannel :0 - is timeshifting (skipped)
[2018-11-24 15:14:49,031] [Log    ] [9        ] [INFO ] - card2:Mediacenter 19 0
[2018-11-24 15:14:49,036] [Log    ] [9        ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=5, tuningdetails=DVBS:tv:SES ASTRA BBC World News Europe HD Freq:11229000 ONID:1 TSID:1002 SID:5001 PMT:0xD4 FTA:True LCN:10000 SymbolRate:22000 Modulation:Mod8Psk Polarisation:LinearV InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet

...and...

[2018-11-24 15:15:21,346] [Log    ] [9        ] [INFO ] - dvbs: Tune:DVBS:tv:GLOBECAST Euronews English HD Freq:11566000 ONID:318 TSID:15300 SID:801 PMT:0x321 FTA:True LCN:10000 SymbolRate:29900 Modulation:Mod8Psk Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:Level1BA band:Universal Pilot:NotSet RollOff:NotSet
[2018-11-24 15:15:21,346] [Log    ] [9        ] [INFO ] - dvbs:  Tune:DVBS:tv:GLOBECAST Euronews English HD Freq:11566000 ONID:318 TSID:15300 SID:801 PMT:0x321 FTA:True LCN:10000 SymbolRate:29900 Modulation:Mod8Psk Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:Level1BA band:Universal Pilot:NotSet RollOff:NotSet
[2018-11-24 15:15:21,346] [Log    ] [9        ] [INFO ] - dvb:GetNewSubChannel:0 #0
[2018-11-24 15:15:21,346] [Log    ] [9        ] [INFO ] - TvDvbChannel ctor new subchIndex:0
[2018-11-24 15:15:21,346] [Log    ] [9        ] [INFO ] - card: AddTuneEvent card: 19 / subch: 0
[2018-11-24 15:15:21,361] [Log    ] [9        ] [INFO ] - LNB low:9750 hi:10600 switch:11700
[2018-11-24 15:15:21,361] [Log    ] [9        ] [INFO ] - dvbs:channel modulation is set to Mod8Psk
[2018-11-24 15:15:21,361] [Log    ] [9        ] [INFO ] - dvbs:channel FECRate is set to Rate3_4
[2018-11-24 15:15:21,361] [Log    ] [9        ] [INFO ] - GenericBDAS:  Level1BA - SendDiSEqCCommand(0x0100)
[2018-11-24 15:15:21,361] [Log    ] [9        ] [INFO ] - GenericBDAS:  SendDiSEqC Command 256
[2018-11-24 15:15:21,361] [Log    ] [9        ] [INFO ] - GenericBDAS:  put_Range:256 success:0
[2018-11-24 15:15:21,377] [Log    ] [9        ] [INFO ] - GenericBDAS:  CommitChanges() Succeeded
[2018-11-24 15:15:21,487] [Log    ] [9        ] [INFO ] - dvb:Submiting tunerequest Channel:Euronews English HD subChannel:0
[2018-11-24 15:15:21,487] [Log    ] [9        ] [INFO ] - dvb:using existing subchannel:0
[2018-11-24 15:15:21,502] [Log    ] [9        ] [INFO ] - dvb:Submit tunerequest size:1 new:0
[2018-11-24 15:15:21,502] [Log    ] [9        ] [INFO ] - subch:0 OnBeforeTune
[2018-11-24 15:15:21,518] [Log    ] [9        ] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest
[2018-11-24 15:15:21,518] [Log    ] [9        ] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest
[2018-11-24 15:15:21,518] [Log    ] [9        ] [INFO ] - subch:0 OnAfterTune
[2018-11-24 15:15:21,518] [Log    ] [9        ] [INFO ] - RunGraph
[2018-11-24 15:15:21,518] [Log    ] [9        ] [INFO ] - subch:0 OnGraphStart
[2018-11-24 15:15:21,533] [Log    ] [9        ] [INFO ] - dvb:  RunGraph
[2018-11-24 15:15:51,439] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:15:51,548] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:15:51,705] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:15:51,798] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:15:51,907] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal waiting 20ms
[2018-11-24 15:16:21,453] [Log    ] [9        ] [INFO ] - dvb:  LockInOnSignal could not lock onto channel - no signal or bad signal
[2018-11-24 15:16:21,453] [Log    ] [9        ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
[2018-11-24 15:16:21,453] [Log    ] [9        ] [INFO ] - DVB subch:0 Decompose()
[2018-11-24 15:16:21,453] [Log    ] [9        ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0
[2018-11-24 15:16:21,453] [Log    ] [9        ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph
[2018-11-24 15:16:21,469] [Log    ] [9        ] [INFO ] - dvb:StopGraph called
[2018-11-24 15:16:21,469] [Log    ] [9        ] [INFO ] - tvcard:FreeAllSubChannels
[2018-11-24 15:16:21,469] [Log    ] [9        ] [INFO ] - dvb:StopGraph
[2018-11-24 15:16:21,484] [Log    ] [9        ] [INFO ] - debug: IMediaControl stopped! hr = 0x0 :)
[2018-11-24 15:16:21,484] [Log    ] [9        ] [INFO ] - card2:Mediacenter 19 -1
[2018-11-24 15:16:21,484] [Log    ] [9        ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=8, tuningdetails=DVBS:tv:GLOBECAST Euronews English HD Freq:11566000 ONID:318 TSID:15300 SID:801 PMT:0x321 FTA:True LCN:10000 SymbolRate:29900 Modulation:Mod8Psk Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:Level1BA band:Universal Pilot:NotSet RollOff:NotSet

Is that correct?

Do those channels always fail to tune?

(There is also another failure for 'BBC World News Europe HD' when the EPG grabber tries to tune it)

 

[Normann]

Yes, that is right. At this session those two channels failed to start. The one that functioned is on its own LNB. I have 3 LNBs and it seems as only one function. At reboot of my computer it can shift to another. Hence my suspicion on the DiSEqC switching.

 

[Normann]

Now, please, give me an advice on how to proceed from here? Anything I can try?

 

[Owlsroost]

Sorry - I've been busy with other stuff...

If I get time over the weekend, I'll try and create a set of patched dll's for MP 1.21pre TV Server based on the (source code) here - MediaPortal/MediaPortal-1 - which I think is what I think 'FreakyJ' used to create the MP 1.6 patch files attached here - TBS: CI/CAM support and other improvements

This seems to be the latest version of the 'TBS patch' source I can find.

 

[Normann]

Wow, I appreciate that! Please, have a nice weekend and maybe we have a solution through next week.

 

[Owlsroost]

I'll try and create a set of patched dll's for MP 1.21pre TV Server based on the (source code) here - MediaPortal/MediaPortal-1 - which I think is what I think 'FreakyJ' used to create the MP 1.6 patch files attached here - TBS: CI/CAM support and other improvements

OK - attached are the two modified dll's (for MP 1.21 pre-release TV Server) plus the 'TbsCIapi.dll' copied from FreakyJ's patch. Have fun testing

 

[Normann]

There seem to have been some progress in that most of the times I reboot the system and try, at least different channels on two LNBs are starting. It is not solid though. This last session is significant, I think. Hope the logs can show you the situation?