Reply to thread

Message

<blockquote data-quote="Lasse H" data-source="post: 1234090" data-attributes="member: 132299">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&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [INFO ] - TVHome.ViewChannelAndCheck(): View channel=TV3 HALLAND
[2017-11-26 13:09:17,651] [Log&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [DEBUG] - TvPlugin: CiMenuHandler attached to new card 9
[2017-11-26 13:09:40,947] [Log&nbsp; &nbsp; ] [24&nbsp; &nbsp; &nbsp;&nbsp; ] [DEBUG] - Callback from tvserver Viaccess Conditional Access
[2017-11-26 13:09:40,948] [Log&nbsp; &nbsp; ] [24&nbsp; &nbsp; &nbsp;&nbsp; ] [DEBUG] - ProcessCiMenu TvLibrary.Interfaces.CiMenu 1
[2017-11-26 13:09:43,907] [Log&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [DEBUG] - TvPlugin: CiMenuHandler attached to new card 9
[2017-11-26 13:09:43,907] [Log&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [INFO ] - succeeded<img src="data:image/gif;base64,R0lGODlhAQABAIAAAAAAAP///yH5BAEAAAAALAAAAAABAAEAAAIBRAA7" class="smilie smilie--sprite smilie--sprite5" alt=":confused:" title="Confused&nbsp; &nbsp; :confused:" loading="lazy" data-shortname=":confused:" />ucceeded TvControl.VirtualCard
[2017-11-26 13:09:43,908] [Log&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [INFO ] - tvhome:startplay
[2017-11-26 13:09:43,909] [Log&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [INFO ] - tvhome:file:C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:43,910] [Log&nbsp; &nbsp; ] [MPMain&nbsp;&nbsp; ] [WARN ] - tvhome:startplay.&nbsp; Phase 1 - 2 ms - Done methodWhat is happening after CiMenuHandler is attached?In tvserver log[2017-11-26 13:09:24,434] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - Controller: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:24,434] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - Controller:&nbsp;&nbsp; delete C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:24,434] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [DEBUG] - Controller: Error &quot;The process cannot access the file 'C:\timeshift\live9-0.ts.tsbuffer' because it is being used by another process.&quot; on delete in CleanTimeshiftFiles
[2017-11-26 13:09:24,435] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - Controller:&nbsp;&nbsp; delete C:\timeshift\live9-0.ts.tsbuffer1.ts
[2017-11-26 13:09:24,435] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [DEBUG] - Controller: Error &quot;The process cannot access the file 'C:\timeshift\live9-0.ts.tsbuffer1.ts' because it is being used by another process.&quot; on delete in CleanTimeshiftFiles
[2017-11-26 13:09:24,435] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - Controller:&nbsp;&nbsp; delete C:\timeshift\live9-0.ts.tsbuffer2.ts
[2017-11-26 13:09:24,436] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - Controller: epg stop
[2017-11-26 13:09:24,437] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - card: StartTimeShifting 9 C:\timeshift\live9-0.ts
[2017-11-26 13:09:24,438] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - card: CAM enabled : True
[2017-11-26 13:09:24,438] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - subch:0 SetTimeShiftFileName:C:\timeshift\live9-0.ts
[2017-11-26 13:09:24,438] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - Set video / audio observer
[2017-11-26 13:09:24,438] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - subch:0 SetTimeShiftFileName fill in pids
[2017-11-26 13:09:24,439] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - subch:0-0 tswriter StartTimeshifting...
[2017-11-26 13:09:24,439] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - card: WaitForFile - waiting _eventAudio &amp; _eventVideo
[2017-11-26 13:09:28,609] [Log&nbsp; &nbsp; ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:28,609] [Log&nbsp; &nbsp; ] [TS_File_Cleanup] [INFO ] - card:&nbsp;&nbsp; trying to delete C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:33,609] [Log&nbsp; &nbsp; ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:33,609] [Log&nbsp; &nbsp; ] [TS_File_Cleanup] [INFO ] - card:&nbsp;&nbsp; trying to delete C:\timeshift\live9-0.ts.tsbufferits 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&nbsp; &nbsp; ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files C:\timeshift\live9-0.ts
[2017-11-26 13:09:33,609] [Log&nbsp; &nbsp; ] [TS_File_Cleanup] [INFO ] - card:&nbsp;&nbsp; trying to delete C:\timeshift\live9-0.ts.tsbuffer
[2017-11-26 13:09:40,944] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] - Turbosight: received MMI response Menu to message Null
[2017-11-26 13:09:40,944] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] - Turbosight: response length = 268
[2017-11-26 13:09:40,944] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] - Turbosight: menu
[2017-11-26 13:09:40,944] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; title&nbsp; &nbsp;&nbsp; = Viaccess Conditional Access
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; sub-title = Meddelande
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; footer&nbsp; &nbsp; = Tryck 'OK' för att bekräfta
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; # entries = 8
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 1&nbsp;&nbsp; = ID: 391 001
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 2&nbsp;&nbsp; = För att se den här kanalen 
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 3&nbsp;&nbsp; = krävs ett programkort från 
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 4&nbsp;&nbsp; = Boxer. Har du redan ett 
[2017-11-26 13:09:40,945] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 5&nbsp;&nbsp; = programkort, sätt i det i 
[2017-11-26 13:09:40,946] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 6&nbsp;&nbsp; = mottagaren.
[2017-11-26 13:09:40,946] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 7&nbsp;&nbsp; = För ytterligare information gå
[2017-11-26 13:09:40,946] [Log&nbsp; &nbsp; ] [Turbosight MMI handler] [DEBUG] -&nbsp;&nbsp; entry 8&nbsp;&nbsp; = in på <a href="http://www.boxer.se/support" target="_blank">www.boxer.se/support</a>
[2017-11-26 13:09:43,890] [Log&nbsp; &nbsp; ] [16&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - PID seen - type = Video
[2017-11-26 13:09:43,890] [Log&nbsp; &nbsp; ] [16&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - audioVideoEventHandler Video
[2017-11-26 13:09:43,891] [Log&nbsp; &nbsp; ] [16&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - PID seen - type = Audio
[2017-11-26 13:09:43,891] [Log&nbsp; &nbsp; ] [16&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - audioVideoEventHandler Audio
[2017-11-26 13:09:43,891] [Log&nbsp; &nbsp; ] [37&nbsp; &nbsp; &nbsp;&nbsp; ] [INFO ] - card: WaitForFile - video and audio are seen after 19,4521126 secondssorry for the long post..regards Lasse H&nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; 

</blockquote>

[QUOTE="Lasse H, post: 1234090, member: 132299"] Thanks for looking at the log files.. Why does it take so long for channel switching then? This is from mediaportal log file.. [INDENT][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 ] - succeeded:confused:ucceeded 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[/INDENT] What is happening after CiMenuHandler is attached? In tvserver log [INDENT][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[/INDENT] 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... [INDENT][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å [URL="http://www.boxer.se/support"]www.boxer.se/support[/URL] [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[/INDENT] sorry for the long post.. regards Lasse H [INDENT] [/INDENT] [/QUOTE]

Verification