TBS: CI/CAM support and other improvements (4 Viewers)

[mm1352000]

Thanks for the log files Lasse

It seems that the only difference between preview and "other" is the CI/CAM menu and MMI handler thread:

[2017-11-20 17:53:00,337] [Log ] [17 ] [DEBUG] - CiMenuSupported called cardid 9
[2017-11-20 17:53:00,339] [Log ] [17 ] [DEBUG] - Turbosight: is CAM ready
[2017-11-20 17:53:00,340] [Log ] [17 ] [DEBUG] - Turbosight: result = True
[2017-11-20 17:53:00,341] [Log ] [17 ] [DEBUG] - CiMenuSupported card TBS 5881 DVB-T/T2 Tuner supported: True
[2017-11-20 17:53:00,341] [Log ] [17 ] [DEBUG] - Turbosight: is CAM ready
[2017-11-20 17:53:00,342] [Log ] [17 ] [DEBUG] - Turbosight: result = True
[2017-11-20 17:53:00,348] [Log ] [17 ] [DEBUG] - CiMenu: registered client event for callback
[2017-11-20 17:53:00,349] [Log ] [17 ] [DEBUG] - TvController: EnableCiMenuHandler called
[2017-11-20 17:53:00,352] [Log ] [17 ] [DEBUG] - Turbosight: starting new MMI handler thread
[2017-11-20 17:53:00,352] [Log ] [17 ] [DEBUG] - TvController: SetCiMenuHandler: result True

Preview doesn't have this.

...however...

This is not something that can be removed. It's the function that gives notifications from the CAM/card when decryption fails for any reason.

 

[Lasse H]

Thanks for looking at the log files..
Why does it take so long for channel switching then?
This is from mediaportal log file..

[2017-11-26 13:09:17,632] [Log ] [MPMain ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=TV3 HALLAND
[2017-11-26 13:09:17,651] [Log ] [MPMain ] [DEBUG] - TvPlugin: CiMenuHandler attached to new card 9
[2017-11-26 13:09:40,947] [Log ] [24 ] [DEBUG] - Callback from tvserver Viaccess Conditional Access
[2017-11-26 13:09:40,948] [Log ] [24 ] [DEBUG] - ProcessCiMenu TvLibrary.Interfaces.CiMenu 1
[2017-11-26 13:09:43,907] [Log ] [MPMain ] [DEBUG] - TvPlugin: CiMenuHandler attached to new card 9
[2017-11-26 13:09:43,907] [Log ] [MPMain ] [INFO ] - succeededucceeded TvControl.VirtualCard
[2017-11-26 13:09:43,908] [Log ] [MPMain ] [INFO ] - tvhome:startplay
[2017-11-26 13:09:43,909] [Log ] [MPMain ] [INFO ] - tvhome:file:C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:43,910] [Log ] [MPMain ] [WARN ] - tvhome:startplay. Phase 1 - 2 ms - Done method​

What is happening after CiMenuHandler is attached?

In tvserver log

[2017-11-26 13:09:24,434] [Log ] [37 ] [INFO ] - Controller: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:24,434] [Log ] [37 ] [INFO ] - Controller: delete C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:24,434] [Log ] [37 ] [DEBUG] - Controller: Error "The process cannot access the file 'C:\timeshift\live9-0.ts.tsbuffer' because it is being used by another process." on delete in CleanTimeshiftFiles
[2017-11-26 13:09:24,435] [Log ] [37 ] [INFO ] - Controller: delete C:\timeshift\live9-0.ts.tsbuffer1.ts
[2017-11-26 13:09:24,435] [Log ] [37 ] [DEBUG] - Controller: Error "The process cannot access the file 'C:\timeshift\live9-0.ts.tsbuffer1.ts' because it is being used by another process." on delete in CleanTimeshiftFiles
[2017-11-26 13:09:24,435] [Log ] [37 ] [INFO ] - Controller: delete C:\timeshift\live9-0.ts.tsbuffer2.ts
[2017-11-26 13:09:24,436] [Log ] [37 ] [INFO ] - Controller: epg stop
[2017-11-26 13:09:24,437] [Log ] [37 ] [INFO ] - card: StartTimeShifting 9 C:\timeshift\live9-0.ts
[2017-11-26 13:09:24,438] [Log ] [37 ] [INFO ] - card: CAM enabled : True
[2017-11-26 13:09:24,438] [Log ] [37 ] [INFO ] - subch:0 SetTimeShiftFileName:C:\timeshift\live9-0.ts
[2017-11-26 13:09:24,438] [Log ] [37 ] [INFO ] - Set video / audio observer
[2017-11-26 13:09:24,438] [Log ] [37 ] [INFO ] - subch:0 SetTimeShiftFileName fill in pids
[2017-11-26 13:09:24,439] [Log ] [37 ] [INFO ] - subch:0-0 tswriter StartTimeshifting...
[2017-11-26 13:09:24,439] [Log ] [37 ] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo
[2017-11-26 13:09:28,609] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:28,609] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:33,609] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:33,609] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete C:\timeshift\live9-0.ts.tsbuffer​

its trying to delete timeshift files.

Then MMI handler gets a response, but PID is seen after that and the channel is decrypted and viewable...

[2017-11-26 13:09:33,609] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:33,609] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:40,944] [Log ] [Turbosight MMI handler] [DEBUG] - Turbosight: received MMI response Menu to message Null
[2017-11-26 13:09:40,944] [Log ] [Turbosight MMI handler] [DEBUG] - Turbosight: response length = 268
[2017-11-26 13:09:40,944] [Log ] [Turbosight MMI handler] [DEBUG] - Turbosight: menu
[2017-11-26 13:09:40,944] [Log ] [Turbosight MMI handler] [DEBUG] - title = Viaccess Conditional Access
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - sub-title = Meddelande
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - footer = Tryck 'OK' för att bekräfta
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - # entries = 8
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - entry 1 = ID: 391 001
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - entry 2 = För att se den här kanalen
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - entry 3 = krävs ett programkort från
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - entry 4 = Boxer. Har du redan ett
[2017-11-26 13:09:40,945] [Log ] [Turbosight MMI handler] [DEBUG] - entry 5 = programkort, sätt i det i
[2017-11-26 13:09:40,946] [Log ] [Turbosight MMI handler] [DEBUG] - entry 6 = mottagaren.
[2017-11-26 13:09:40,946] [Log ] [Turbosight MMI handler] [DEBUG] - entry 7 = För ytterligare information gå
[2017-11-26 13:09:40,946] [Log ] [Turbosight MMI handler] [DEBUG] - entry 8 = in på www.boxer.se/support
[2017-11-26 13:09:43,890] [Log ] [16 ] [INFO ] - PID seen - type = Video
[2017-11-26 13:09:43,890] [Log ] [16 ] [INFO ] - audioVideoEventHandler Video
[2017-11-26 13:09:43,891] [Log ] [16 ] [INFO ] - PID seen - type = Audio
[2017-11-26 13:09:43,891] [Log ] [16 ] [INFO ] - audioVideoEventHandler Audio
[2017-11-26 13:09:43,891] [Log ] [37 ] [INFO ] - card: WaitForFile - video and audio are seen after 19,4521126 seconds​

sorry for the long post..
regards Lasse H

​

 

[mm1352000]

Why does it take so long for channel switching then?

I have absolutely no idea. It seems to be related to the CI/CAM/smartcard... but I cannot say anything more because those things are a "black box" (unknown) to MP. You'd have to ask TBS, Viaccess (CAM vendor), smartcard vendor, TV provider... some other company/person.

What is happening after CiMenuHandler is attached?

TV Server starts to check for messages from the CI/CAM/smartcard once every 2 seconds.

I repeat what I said in my previous reply: this is done to get any important messages from the CI/CAM/smartcard (eg. "CAM removed", "CAM inserted", "failed to decrypt" etc. etc. etc.).

Normally this does not cause a problem. You can search this thread and see that nobody has reported a similar problem in the last 6 years, so I guess the issue is particular to your CAM.

If I were you I would try to reproduce the problem in tbsViewer and contact TBS support.

 

[ratata]

...why does it take so long for channel switching then?

Hello Lasse.

I don't understand. Do you mean it takes long time for a channel change when you're using MP as a client on one computer and the TV server on another computer? If that's the case you should try to disable IPv6 on both machines and use IPv4 solely. You don't have much use for IPv6 yet anyway but MP gets extremly confused of it. Try disabling it.

Best regards (morsning korsning)
ratata

 

[mm1352000]

but MP gets extremly confused of it.

Yes, that can be true... but the delay seems to be on the server side (delay in decrypted video/audio receive from the CAM), so in this case I don't think it's the IPv6 issue.

 

[Lasse H]

Hello Lasse.

I don't understand. Do you mean it takes long time for a channel change when you're using MP as a client on one computer and the TV server on another computer? If that's the case you should try to disable IPv6 on both machines and use IPv4 solely. You don't have much use for IPv6 yet anyway but MP gets extremly confused of it. Try disabling it.

Best regards (morsning korsning)
ratata

Its single seat setup.. No problem when previewing the encrypted channels in tvserver setup tool. No apparent delays when testing timeshifting.
Problem occurs when using MP client.. My conclusion is that the CAM, Subscription card and tbs5881 is ok. The difference that i noticed is different users, HTPC for MP client and Tvserversetup when testing with tvserver setup tool...
This file.. TvLibrary.dll can it be related to this kind of behavior.. Maybe i have the wrong versions?

 

[g00se]

Currently on 1.16 because the motor patch linked here works but I need the delay patch also which is in the testing area but that lists for 1.15 only.

Does that patch contain the fixes for TBS/Diseqc issues if I downgrade to 1.15?
Have any of these patches been integrated into 1.17/1.18/betas?

 

[andcar]

I am using MP 1.18 and a TBS6290 DVB-T2/T and uses TBS latest driver.
I have the patch installed and allmost everything works as it should except when returning from computer sleep or a restart of the computer.
Then the first time I try to look at a encrypted channel, MediaPortal says "encrypted channel". Second time I try the same channel or any other encrypted channel the channel appears as it should.
The problem is that the first sheduled recording after sleep or computer restart will fail due to this.
Until I have found a sollution, the computer is never turned off or allowed to sleep, but that is not a good long term sollution.

I have mailed TBS support (which allways is excellent). They claims that this is not a driver issue but a software issue and they ask me to post the problem here.

 

[mm1352000]

Does that patch contain the fixes for TBS/Diseqc issues if I downgrade to 1.15?

Not sure which patch you're referring to, so I cannot give an answer.

Have any of these patches been integrated into 1.17/1.18/betas?

No.

...and they ask me to post the problem here.

You'll need to provide full log files if you want help here.

 

[g00se]

This patch, [WiP] - MediaPortal DiseqC Motor Code Patch

Basically I want both the ability to move my dish using my TBS card and have the delay so that I can trust that recording on schedule will happen between satellites of a large degree of separation, anything greater that 10 degrees will tend to timeout instead of waiting.. Might be asking a bit too much I guess.