home
products
contribute
download
documentation
forum
Home
Forums
New posts
Search forums
What's new
New posts
All posts
Latest activity
Members
Registered members
Current visitors
Donate
Log in
Register
What's new
Search
Search
Search titles only
By:
New posts
Search forums
Search titles only
By:
Menu
Log in
Register
Navigation
Install the app
Install
More options
Contact us
Close Menu
Forums
MediaPortal 2
General
Very slow (over a minute) change between scrambled channels
Contact us
RSS
JavaScript is disabled. For a better experience, please enable JavaScript in your browser before proceeding.
You are using an out of date browser. It may not display this or other websites correctly.
You should upgrade or use an
alternative browser
.
Reply to thread
Message
<blockquote data-quote="morpheus_xx" data-source="post: 1177256" data-attributes="member: 48495"><p>Strange issue.</p><p></p><p>The Server is able to tune the channel normal:</p><p>[code][2016-03-01 21:01:01,863] [164562 ] [29 ] [DEBUG] - dvbc: Tune:DVBC:tv:Ziggo Discovery HD Freq:378000 ONID:1536 TSID:2063 SID:19222 PMT:0x8FC FTA:False LCN:938 SymbolRate:6900 Modulation:Mod256Qam</p><p>[2016-03-01 21:01:01,866] [164565 ] [29 ] [INFO ] - dvb:Submiting tunerequest Channel:Discovery HD subChannel:-1</p><p>[2016-03-01 21:01:01,867] [164566 ] [29 ] [INFO ] - dvb:Getting new subchannel</p><p>[2016-03-01 21:01:01,868] [164567 ] [29 ] [INFO ] - dvb:GetNewSubChannel:0 #0</p><p>[2016-03-01 21:01:01,870] [164569 ] [29 ] [DEBUG] - TvDvbChannel ctor new subchIndex:0</p><p>[2016-03-01 21:01:01,870] [164569 ] [29 ] [INFO ] - card: AddTuneEvent card: 3 / subch: 0</p><p>[2016-03-01 21:01:01,871] [164570 ] [29 ] [INFO ] - dvb:Submit tunerequest size:1 new:0</p><p>[2016-03-01 21:01:01,871] [164570 ] [29 ] [DEBUG] - subch:0 OnBeforeTune</p><p>[2016-03-01 21:01:01,872] [164571 ] [29 ] [DEBUG] - dvb:Submit tunerequest calling put_TuneRequest</p><p>[2016-03-01 21:01:01,873] [164572 ] [29 ] [DEBUG] - dvb:Submit tunerequest done calling put_TuneRequest</p><p>[2016-03-01 21:01:01,874] [164573 ] [29 ] [DEBUG] - subch:0 OnAfterTune</p><p>[2016-03-01 21:01:01,874] [164573 ] [29 ] [INFO ] - RunGraph</p><p>[2016-03-01 21:01:01,875] [164574 ] [29 ] [DEBUG] - subch:0 OnGraphStart</p><p>[2016-03-01 21:01:01,875] [164574 ] [29 ] [INFO ] - dvb: RunGraph</p><p>[2016-03-01 21:01:02,103] [164802 ] [29 ] [DEBUG] - dvb: LockInOnSignal ok</p><p>[2016-03-01 21:01:02,104] [164803 ] [29 ] [DEBUG] - subch:0 OnGraphStarted</p><p>[2016-03-01 21:01:02,104] [164803 ] [29 ] [INFO ] - subch:0 SetupPmtGrabber:pid 8FC sid:4B16</p><p>[2016-03-01 21:01:02,105] [164804 ] [29 ] [DEBUG] - subch:0 set pmt grabber pmt:8FC sid:4B16</p><p>[2016-03-01 21:01:02,105] [164804 ] [29 ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time</p><p>[2016-03-01 21:01:02,106] [164805 ] [29 ] [DEBUG] - WaitForPMT: Waiting for PMT 8FC</p><p>[2016-03-01 21:01:02,263] [164962 ] [45 ] [DEBUG] - subch:0 OnPMTReceived() pmt:8FC ran:True dynamic:False</p><p>[2016-03-01 21:01:02,264] [164963 ] [29 ] [DEBUG] - WaitForPMT: Found PMT after 0.1580091 seconds.</p><p>[2016-03-01 21:01:02,264] [164963 ] [29 ] [INFO ] - subch:0 SendPmt:4B16 4B16 FFFFFFFF 19</p><p>[2016-03-01 21:01:02,265] [164964 ] [29 ] [DEBUG] - Decode pmt</p><p>[2016-03-01 21:01:02,266] [164965 ] [29 ] [INFO ] - descr1 len:6 9 4 18 1 e0 30</p><p>[2016-03-01 21:01:02,266] [164965 ] [29 ] [INFO ] - descr1 len:6 9 4 18 50 e0 31</p><p>[2016-03-01 21:01:02,267] [164966 ] [29 ] [INFO ] - descr1 len:6 9 4 6 2 e0 c8</p><p>[2016-03-01 21:01:02,268] [164967 ] [29 ] [INFO ] - descr1 len:6 9 4 6 4 e1 2d</p><p>[2016-03-01 21:01:02,268] [164967 ] [29 ] [DEBUG] - Set descriptor data with length 6</p><p>[2016-03-01 21:01:02,269] [164968 ] [29 ] [DEBUG] - Set descriptor data with length 6</p><p>[2016-03-01 21:01:02,269] [164968 ] [29 ] [DEBUG] - subch:0 SendPMT version:25 len:75 19222</p><p>[2016-03-01 21:01:02,270] [164969 ] [29 ] [DEBUG] - Digital Devices:--> Setting service id 19222 for decrypting returned 0</p><p>[2016-03-01 21:01:02,270] [164969 ] [29 ] [DEBUG] - subch:0 cam flags:True</p><p>[2016-03-01 21:01:02,271] [164970 ] [29 ] [DEBUG] - subch:0 SetMpegPidMapping</p><p>[2016-03-01 21:01:02,271] [164970 ] [29 ] [DEBUG] - subch:0 pid:8FD pcr</p><p>[2016-03-01 21:01:02,271] [164970 ] [29 ] [DEBUG] - subch:0 pid:8FC pmt</p><p>[2016-03-01 21:01:02,272] [164971 ] [29 ] [DEBUG] - subch:0 pid:8FD video type:H.264</p><p>[2016-03-01 21:01:02,272] [164971 ] [29 ] [DEBUG] - subch:0 map pid:8FD video type:H.264</p><p>[2016-03-01 21:01:02,273] [164972 ] [29 ] [DEBUG] - subch:0 pid:907 audio lang:dut type:AC3</p><p>[2016-03-01 21:01:02,273] [164972 ] [29 ] [DEBUG] - subch:0 map pid:907 audio lang:dut type:AC3</p><p>[2016-03-01 21:01:02,274] [164973 ] [29 ] [DEBUG] - subch:0 pid:908 audio lang:dut type:MPEG-2</p><p>[2016-03-01 21:01:02,274] [164973 ] [29 ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed</p><p>[2016-03-01 21:01:02,275] [164974 ] [29 ] [INFO ] - subch:0 stop tif</p><p>[2016-03-01 21:01:02,275] [164974 ] [29 ] [DEBUG] - WaitForPmt: PMT handling took 0.0110006 seconds.</p><p>[2016-03-01 21:01:02,276] [164975 ] [29 ] [DEBUG] - card: Tuner locked: True</p><p>[2016-03-01 21:01:02,276] [164975 ] [29 ] [INFO ] - **************************************************</p><p>[2016-03-01 21:01:02,277] [164976 ] [29 ] [INFO ] - ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 85 *****</p><p>[2016-03-01 21:01:02,277] [164976 ] [29 ] [INFO ] - **************************************************</p><p>[2016-03-01 21:01:02,278] [164977 ] [29 ] [DEBUG] - card: tuned user: setuptv subchannel: 0</p><p>[2016-03-01 21:01:02,278] [164977 ] [29 ] [INFO ] - user:setuptv add</p><p>[2016-03-01 21:01:02,278] [164977 ] [29 ] [INFO ] - card2:setuptv 3 0</p><p>[2016-03-01 21:01:02,279] [164978 ] [29 ] [DEBUG] - Controller: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts</p><p>[2016-03-01 21:01:02,280] [164979 ] [29 ] [DEBUG] - Controller: epg stop</p><p>[2016-03-01 21:01:02,284] [164983 ] [29 ] [DEBUG] - card: StartTimeShifting 3 C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts</p><p>[2016-03-01 21:01:02,285] [164984 ] [29 ] [DEBUG] - card: CAM enabled : True</p><p>[2016-03-01 21:01:02,285] [164984 ] [29 ] [DEBUG] - subch:0 SetTimeShiftFileName:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts</p><p>[2016-03-01 21:01:02,286] [164985 ] [29 ] [DEBUG] - Set video / audio observer</p><p>[2016-03-01 21:01:02,287] [164986 ] [29 ] [DEBUG] - subch:0 SetTimeShiftFileName fill in pids</p><p>[2016-03-01 21:01:02,288] [164987 ] [29 ] [DEBUG] - subch:0-0 tswriter StartTimeshifting...</p><p>[2016-03-01 21:01:02,290] [164989 ] [29 ] [DEBUG] - card: WaitForFile - waiting _eventAudio & _eventVideo</p><p>[2016-03-01 21:01:02,460] [165159 ] [45 ] [DEBUG] - PID seen - type = Audio</p><p>[2016-03-01 21:01:02,461] [165160 ] [45 ] [INFO ] - audioVideoEventHandler Audio</p><p>[2016-03-01 21:01:02,472] [165171 ] [45 ] [DEBUG] - PID seen - type = Video</p><p>[2016-03-01 21:01:02,473] [165172 ] [45 ] [INFO ] - audioVideoEventHandler Video</p><p>[2016-03-01 21:01:02,474] [165173 ] [29 ] [DEBUG] - card: WaitForFile - video and audio are seen after 0.1840105 seconds</p><p>[2016-03-01 21:01:02,476] [165175 ] [29 ] [INFO ] - user:setuptv card:3 sub:0 add stream:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer</p><p>[2016-03-01 21:01:02,477] [165176 ] [29 ] [DEBUG] - RTSP: add stream stream3.0 file:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer</p><p>[/code]</p><p>--> ~ 1 second for tuning.</p><p></p><p>The SetupTv log shows very long times for playing back the stream:</p><p>[code][2016-03-01 21:01:02,501] [145286 ] [SetupTv ] [INFO ] - preview Discovery HD user:-1 -1 setuptv C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer</p><p>[2016-03-01 21:01:02,502] [145287 ] [SetupTv ] [DEBUG] - play:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer</p><p>[2016-03-01 21:01:02,506] [145291 ] [SetupTv ] [INFO ] - TSReaderPlayer:add TsReader to graph</p><p>[2016-03-01 21:01:02,506] [145291 ] [SetupTv ] [DEBUG] - load file in Ts</p><p>[2016-03-01 21:01:12,524] [155308 ] [SetupTv ] [INFO ] - TSReaderPlayer:render TsReader outputs</p><p>[2016-03-01 21:02:09,009] [211794 ] [SetupTv ] [DEBUG] - run graph</p><p>[2016-03-01 21:02:09,021] [211806 ] [SetupTv ] [DEBUG] - TSReaderPlayer:running:1[/code]</p><p>--> looks like playback graph construction takes 1 minute?!</p><p></p><p>This sounds like an issue with DirectShow decoders that cause trouble when the playback graph is constructed. Did you install any new codec(packs) or similar stuff?</p><p></p><p>Did you use "GraphEdit" or "GraphStudio" before? It can help to diagnose the graph construction issues.</p></blockquote><p></p>
[QUOTE="morpheus_xx, post: 1177256, member: 48495"] Strange issue. The Server is able to tune the channel normal: [code][2016-03-01 21:01:01,863] [164562 ] [29 ] [DEBUG] - dvbc: Tune:DVBC:tv:Ziggo Discovery HD Freq:378000 ONID:1536 TSID:2063 SID:19222 PMT:0x8FC FTA:False LCN:938 SymbolRate:6900 Modulation:Mod256Qam [2016-03-01 21:01:01,866] [164565 ] [29 ] [INFO ] - dvb:Submiting tunerequest Channel:Discovery HD subChannel:-1 [2016-03-01 21:01:01,867] [164566 ] [29 ] [INFO ] - dvb:Getting new subchannel [2016-03-01 21:01:01,868] [164567 ] [29 ] [INFO ] - dvb:GetNewSubChannel:0 #0 [2016-03-01 21:01:01,870] [164569 ] [29 ] [DEBUG] - TvDvbChannel ctor new subchIndex:0 [2016-03-01 21:01:01,870] [164569 ] [29 ] [INFO ] - card: AddTuneEvent card: 3 / subch: 0 [2016-03-01 21:01:01,871] [164570 ] [29 ] [INFO ] - dvb:Submit tunerequest size:1 new:0 [2016-03-01 21:01:01,871] [164570 ] [29 ] [DEBUG] - subch:0 OnBeforeTune [2016-03-01 21:01:01,872] [164571 ] [29 ] [DEBUG] - dvb:Submit tunerequest calling put_TuneRequest [2016-03-01 21:01:01,873] [164572 ] [29 ] [DEBUG] - dvb:Submit tunerequest done calling put_TuneRequest [2016-03-01 21:01:01,874] [164573 ] [29 ] [DEBUG] - subch:0 OnAfterTune [2016-03-01 21:01:01,874] [164573 ] [29 ] [INFO ] - RunGraph [2016-03-01 21:01:01,875] [164574 ] [29 ] [DEBUG] - subch:0 OnGraphStart [2016-03-01 21:01:01,875] [164574 ] [29 ] [INFO ] - dvb: RunGraph [2016-03-01 21:01:02,103] [164802 ] [29 ] [DEBUG] - dvb: LockInOnSignal ok [2016-03-01 21:01:02,104] [164803 ] [29 ] [DEBUG] - subch:0 OnGraphStarted [2016-03-01 21:01:02,104] [164803 ] [29 ] [INFO ] - subch:0 SetupPmtGrabber:pid 8FC sid:4B16 [2016-03-01 21:01:02,105] [164804 ] [29 ] [DEBUG] - subch:0 set pmt grabber pmt:8FC sid:4B16 [2016-03-01 21:01:02,105] [164804 ] [29 ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time [2016-03-01 21:01:02,106] [164805 ] [29 ] [DEBUG] - WaitForPMT: Waiting for PMT 8FC [2016-03-01 21:01:02,263] [164962 ] [45 ] [DEBUG] - subch:0 OnPMTReceived() pmt:8FC ran:True dynamic:False [2016-03-01 21:01:02,264] [164963 ] [29 ] [DEBUG] - WaitForPMT: Found PMT after 0.1580091 seconds. [2016-03-01 21:01:02,264] [164963 ] [29 ] [INFO ] - subch:0 SendPmt:4B16 4B16 FFFFFFFF 19 [2016-03-01 21:01:02,265] [164964 ] [29 ] [DEBUG] - Decode pmt [2016-03-01 21:01:02,266] [164965 ] [29 ] [INFO ] - descr1 len:6 9 4 18 1 e0 30 [2016-03-01 21:01:02,266] [164965 ] [29 ] [INFO ] - descr1 len:6 9 4 18 50 e0 31 [2016-03-01 21:01:02,267] [164966 ] [29 ] [INFO ] - descr1 len:6 9 4 6 2 e0 c8 [2016-03-01 21:01:02,268] [164967 ] [29 ] [INFO ] - descr1 len:6 9 4 6 4 e1 2d [2016-03-01 21:01:02,268] [164967 ] [29 ] [DEBUG] - Set descriptor data with length 6 [2016-03-01 21:01:02,269] [164968 ] [29 ] [DEBUG] - Set descriptor data with length 6 [2016-03-01 21:01:02,269] [164968 ] [29 ] [DEBUG] - subch:0 SendPMT version:25 len:75 19222 [2016-03-01 21:01:02,270] [164969 ] [29 ] [DEBUG] - Digital Devices:--> Setting service id 19222 for decrypting returned 0 [2016-03-01 21:01:02,270] [164969 ] [29 ] [DEBUG] - subch:0 cam flags:True [2016-03-01 21:01:02,271] [164970 ] [29 ] [DEBUG] - subch:0 SetMpegPidMapping [2016-03-01 21:01:02,271] [164970 ] [29 ] [DEBUG] - subch:0 pid:8FD pcr [2016-03-01 21:01:02,271] [164970 ] [29 ] [DEBUG] - subch:0 pid:8FC pmt [2016-03-01 21:01:02,272] [164971 ] [29 ] [DEBUG] - subch:0 pid:8FD video type:H.264 [2016-03-01 21:01:02,272] [164971 ] [29 ] [DEBUG] - subch:0 map pid:8FD video type:H.264 [2016-03-01 21:01:02,273] [164972 ] [29 ] [DEBUG] - subch:0 pid:907 audio lang:dut type:AC3 [2016-03-01 21:01:02,273] [164972 ] [29 ] [DEBUG] - subch:0 map pid:907 audio lang:dut type:AC3 [2016-03-01 21:01:02,274] [164973 ] [29 ] [DEBUG] - subch:0 pid:908 audio lang:dut type:MPEG-2 [2016-03-01 21:01:02,274] [164973 ] [29 ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed [2016-03-01 21:01:02,275] [164974 ] [29 ] [INFO ] - subch:0 stop tif [2016-03-01 21:01:02,275] [164974 ] [29 ] [DEBUG] - WaitForPmt: PMT handling took 0.0110006 seconds. [2016-03-01 21:01:02,276] [164975 ] [29 ] [DEBUG] - card: Tuner locked: True [2016-03-01 21:01:02,276] [164975 ] [29 ] [INFO ] - ************************************************** [2016-03-01 21:01:02,277] [164976 ] [29 ] [INFO ] - ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 85 ***** [2016-03-01 21:01:02,277] [164976 ] [29 ] [INFO ] - ************************************************** [2016-03-01 21:01:02,278] [164977 ] [29 ] [DEBUG] - card: tuned user: setuptv subchannel: 0 [2016-03-01 21:01:02,278] [164977 ] [29 ] [INFO ] - user:setuptv add [2016-03-01 21:01:02,278] [164977 ] [29 ] [INFO ] - card2:setuptv 3 0 [2016-03-01 21:01:02,279] [164978 ] [29 ] [DEBUG] - Controller: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2016-03-01 21:01:02,280] [164979 ] [29 ] [DEBUG] - Controller: epg stop [2016-03-01 21:01:02,284] [164983 ] [29 ] [DEBUG] - card: StartTimeShifting 3 C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2016-03-01 21:01:02,285] [164984 ] [29 ] [DEBUG] - card: CAM enabled : True [2016-03-01 21:01:02,285] [164984 ] [29 ] [DEBUG] - subch:0 SetTimeShiftFileName:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts [2016-03-01 21:01:02,286] [164985 ] [29 ] [DEBUG] - Set video / audio observer [2016-03-01 21:01:02,287] [164986 ] [29 ] [DEBUG] - subch:0 SetTimeShiftFileName fill in pids [2016-03-01 21:01:02,288] [164987 ] [29 ] [DEBUG] - subch:0-0 tswriter StartTimeshifting... [2016-03-01 21:01:02,290] [164989 ] [29 ] [DEBUG] - card: WaitForFile - waiting _eventAudio & _eventVideo [2016-03-01 21:01:02,460] [165159 ] [45 ] [DEBUG] - PID seen - type = Audio [2016-03-01 21:01:02,461] [165160 ] [45 ] [INFO ] - audioVideoEventHandler Audio [2016-03-01 21:01:02,472] [165171 ] [45 ] [DEBUG] - PID seen - type = Video [2016-03-01 21:01:02,473] [165172 ] [45 ] [INFO ] - audioVideoEventHandler Video [2016-03-01 21:01:02,474] [165173 ] [29 ] [DEBUG] - card: WaitForFile - video and audio are seen after 0.1840105 seconds [2016-03-01 21:01:02,476] [165175 ] [29 ] [INFO ] - user:setuptv card:3 sub:0 add stream:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2016-03-01 21:01:02,477] [165176 ] [29 ] [DEBUG] - RTSP: add stream stream3.0 file:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [/code] --> ~ 1 second for tuning. The SetupTv log shows very long times for playing back the stream: [code][2016-03-01 21:01:02,501] [145286 ] [SetupTv ] [INFO ] - preview Discovery HD user:-1 -1 setuptv C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2016-03-01 21:01:02,502] [145287 ] [SetupTv ] [DEBUG] - play:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live3-0.ts.tsbuffer [2016-03-01 21:01:02,506] [145291 ] [SetupTv ] [INFO ] - TSReaderPlayer:add TsReader to graph [2016-03-01 21:01:02,506] [145291 ] [SetupTv ] [DEBUG] - load file in Ts [2016-03-01 21:01:12,524] [155308 ] [SetupTv ] [INFO ] - TSReaderPlayer:render TsReader outputs [2016-03-01 21:02:09,009] [211794 ] [SetupTv ] [DEBUG] - run graph [2016-03-01 21:02:09,021] [211806 ] [SetupTv ] [DEBUG] - TSReaderPlayer:running:1[/code] --> looks like playback graph construction takes 1 minute?! This sounds like an issue with DirectShow decoders that cause trouble when the playback graph is constructed. Did you install any new codec(packs) or similar stuff? Did you use "GraphEdit" or "GraphStudio" before? It can help to diagnose the graph construction issues. [/QUOTE]
Insert quotes…
Verification
Post reply
Forums
MediaPortal 2
General
Very slow (over a minute) change between scrambled channels
Contact us
RSS
Top
Bottom