[Finished] MP1-4777 Additional info for video and audio

Sebastiii

Development Group
  • Team MediaPortal
  • November 12, 2007
    16,341
    113
    France
    Country flag
    Ok i will stop now :) (will link to the sample)
    I really don't understand what happen, something on C# (from MP1-4777) break TsReader WIP. But i have try to debug and seems that it doesn the same thing between Master and MP1-4777 (when the graph is building).

    So really no clue.
     

    Sebastiii

    Development Group
  • Team MediaPortal
  • November 12, 2007
    16,341
    113
    France
    Country flag
    @yartat :)
    Good news, i have found something but no clue why :

    If i comment out the line, it works and fast (no blackscreen delay too)

    C#:
              //if (_player.CurrentAudioStream != value)
    C#:
    public static int CurrentAudioStream
        {
          get
          {
            if (_player == null)
            {
              return 0;
            }
            return _player.CurrentAudioStream;
          }
          set
          {
            if (_player != null)
            {
              //if (_player.CurrentAudioStream != value)
              {
                var oldStream = _player.CurrentAudio;
                _player.CurrentAudioStream = value;
                OnStreamChanged(StreamKind.Audio, oldStream, _player.CurrentAudio, _player);
              }
            }
          }
        }
     

    Owlsroost

    Development Group
  • Team MediaPortal
  • October 28, 2008
    5,538
    113
    Cambridge
    Country flag
    @Owlsroost this is log when it failed (TsReader WIP + MP1-4777).
    I will upload log with TsReader without MP1-4777 to compare (ASAP)
    TsReader.ax is requesting an audio stream selection (via a callback) since there are multiple audio streams:

    [2016-03-05 17:59:47,807] [15931010] [ f8] - OnNewChannel: OnRequestAudioChange()

    ...but it looks like MP player is never responding to it - you should get a 'SetAudioStream' in TsReader log when this happens, but it's not there in the log.

    So it looks like that part of MP TsReader player is broken...which is why the audio pin connection fails (because the audio stream type is not set).

    Also, the stream that TsReader.ax is reading corrupted somehow - note all the PES fail errors. Maybe the MP player is in a weird state by this point?

    Code:
    [2016-03-05 17:59:47,807] [15931010] [  f8] - OnNewChannel: New channel found (PAT/PMT/SDT changed)
    [2016-03-05 17:59:47,807] [15931010] [  f8] -  pcr      pid: 0x  30
    [2016-03-05 17:59:47,807] [15931010] [  f8] -  pmt      pid: 0x  20
    [2016-03-05 17:59:47,807] [15931010] [18b4] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 17:59:47,807] [15931010] [  f8] -  video    pid: 0x  30 type: H264 DescriptorData: 0x 0
    [2016-03-05 17:59:47,807] [15931010] [18b4] - demux:flushing
    [2016-03-05 17:59:47,807] [15931010] [  f8] -  audio    pid: 0x  40 language: fra type: AC3
    [2016-03-05 17:59:47,807] [15931010] [18b4] - demux:flush audio
    [2016-03-05 17:59:47,807] [15931010] [  f8] -  audio    pid: 0x  41 language: qad type: AC3
    [2016-03-05 17:59:47,807] [15931010] [  f8] -  Subtitle pid: 0x  50 language: fra type: DVB subtitle 2
    [2016-03-05 17:59:47,807] [15931010] [18b4] - demux:flush video
    [2016-03-05 17:59:47,807] [15931010] [  f8] - OnNewChannel: OnRequestAudioChange()
    [2016-03-05 17:59:47,807] [15931010] [18b4] - demux:flush subtitle
    [2016-03-05 17:59:47,807] [15931010] [  f8] - demux:Wait for Audio stream selection :1
    [2016-03-05 17:59:47,822] [15931010] [  f8] - OnNewChannel: New Audio stream type = 0xffffffff
    [2016-03-05 17:59:47,825] [15931010] [  f8] - DeMultiplexer: First Gop after new PAT, 1920x1080 @ 16:9, 24.908Hz interlaced
    [2016-03-05 17:59:47,825] [15931010] [  f8] - DeMultiplexer: 30 new video format, 1920x1080 @ 16:9, 24.908Hz interlaced
    [2016-03-05 17:59:47,920] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = 5-a6-fb-93-2a-76-27-df, TS hdr = 47-40-30-10-5-a6-fb-93-2a-76
    [2016-03-05 17:59:47,920] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = e8-ea-ea-a4-25-7e-ba-dc, TS hdr = 47-40-30-1d-e8-ea-ea-a4-25-7e
    [2016-03-05 17:59:47,920] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = 83-82-2f-b9-b6-ec-31-8f, TS hdr = 47-40-30-17-83-82-2f-b9-b6-ec
    [2016-03-05 17:59:47,921] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = e3-f7-2b-8d-56-ee-d3-1a, TS hdr = 47-40-30-10-e3-f7-2b-8d-56-ee
    [2016-03-05 17:59:47,921] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = 16-a1-7c-32-75-b2-1-e8, TS hdr = 47-40-30-1c-16-a1-7c-32-75-b2
    [2016-03-05 17:59:47,921] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = c6-a3-86-a2-f8-85-8-6f, TS hdr = 47-40-30-38-1-60-c6-a3-86-a2
    [2016-03-05 17:59:47,921] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = 42-c7-d6-ff-b7-ef-e3-8e, TS hdr = 47-40-30-14-42-c7-d6-ff-b7-ef
    [2016-03-05 17:59:47,921] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = c5-f7-cf-46-21-ce-b9-33, TS hdr = 47-40-30-14-c5-f7-cf-46-21-ce
    [2016-03-05 17:59:47,921] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = 6d-cd-16-d3-c2-43-5a-5b, TS hdr = 47-40-30-1d-6d-cd-16-d3-c2-43
    [2016-03-05 17:59:47,922] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = e4-20-fd-74-6-8b-4c-3b, TS hdr = 47-40-30-1d-e4-20-fd-74-6-8b
    [2016-03-05 17:59:47,922] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = ad-e6-68-44-bd-4d-b0-5c, TS hdr = 47-40-30-1e-ad-e6-68-44-bd-4d
    [2016-03-05 17:59:47,922] [15931010] [  f8] - PES H264 0-0-1 fail, PES hdr = 51-d0-2e-66-a2-42-61-f9, TS hdr = 47-40-30-19-51-d0-2e-66-a2-42
     

    Sebastiii

    Development Group
  • Team MediaPortal
  • November 12, 2007
    16,341
    113
    France
    Country flag
    So to summarize :

    Original MP1-4777 + master TsReader -> Give a delay when playing some .TS (maybe other type but i have try only with .TS)
    Original MP1-4777 + WIP TsReader -> Give a lock on MP (because TsReader get lost (audPin:GetMediaType() - Timeout) when playing some .TS (maybe other type but i have try only with .TS)

    If i comment out the line from above post, then it works, no more delay (black) and lock.
    I have really no clue why it happen, but it seems that something need to be init somewhere :)
     

    Sebastiii

    Development Group
  • Team MediaPortal
  • November 12, 2007
    16,341
    113
    France
    Country flag
    @Owlsroost this is log when it failed (TsReader WIP + MP1-4777).
    I will upload log with TsReader without MP1-4777 to compare (ASAP)
    TsReader.ax is requesting an audio stream selection (via a callback) since there are multiple audio streams:

    [2016-03-05 17:59:47,807] [15931010] [ f8] - OnNewChannel: OnRequestAudioChange()

    ...but it looks like MP player is never responding to it - you should get a 'SetAudioStream' in TsReader log when this happens, but it's not there in the log.

    So it looks like that part of MP TsReader player is broken...which is why the audio pin connection fails (because the audio stream type is not set).

    Also, the stream that TsReader.ax is reading corrupted somehow - note all the PES fail errors. Maybe the MP player is in a weird state by this point?
    When i have retry the lock, the PES was not there, so it's maybe my network somehow when i have doing the log, so we can avoid that part but thanks for telling/explaning it :)
    So i hope that your findind can help @yartat because yes for sure now something is not init.

    I will post log with the comment out line and we should find 'SetAudioStream' i suppose now :)
     

    Sebastiii

    Development Group
  • Team MediaPortal
  • November 12, 2007
    16,341
    113
    France
    Country flag
    Here we go for the log : and you are right this time : [2016-03-05 20:03:00,662] [152ad188] [1c04] - SetAudioStream : 0

    [2016-03-05 20:03:00,628] [ 0] [1c04] - =================== New filter instance =========================================
    [2016-03-05 20:03:00,628] [ 0] [1c04] - Logging format: [Date Time] [InstanceID-instanceCount] [ThreadID] Message....
    [2016-03-05 20:03:00,628] [ 0] [1c04] - ==================================================================================
    [2016-03-05 20:03:00,628] [ 0] [1c04] - demux: Start file read thread
    [2016-03-05 20:03:00,628] [ 0] [1c04] - CRTSPClient::CRTSPClient()
    [2016-03-05 20:03:00,628] [ 0] [1c04] - CMemoryBuffer::ctor
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - ------------- v4.2.0.17 ------------- instanceCount:1
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - CTsReaderFilter::ctor
    [2016-03-05 20:03:00,628] [152ad188] [233c] - CDeMultiplexer::ThreadProc start(), threadID:0x233c
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- UseFPSfromDTSPTS = yes
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Buffering delay = 0 ms
    [2016-03-05 20:03:00,628] [152ad188] [233c] - TsAVRT::confused:etMMCSThread - Priority: 0, AvrtHandle: 5996
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Audio delay = 0 ms
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Slow Play = 0 PPM
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- AutoSpeedAdjust = 2
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- LAV_AutoAVSync = 0
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Slow motion video allowed during zapping = yes
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - Wait for seeking to eof - false - constructor
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - SetMediaPosnUpdate : m_pClock invalid
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - CTsReaderFilter::confused:tart duration thread
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - CTsReaderFilter::timeGetTime():0x2059943, m_tGTStartTime:0xc2059943, GET_TIME_NOW:0x40000000, timer res:1 ms
    [2016-03-05 20:03:00,629] [152ad188] [14e8] - CTsReaderFilter::ThreadProc start(), threadID:0x14e8
    [2016-03-05 20:03:00,639] [152ad188] [1c04] - SetRelaxedMode
    [2016-03-05 20:03:00,639] [152ad188] [1c04] - Relaxed discontinuities filtering
    [2016-03-05 20:03:00,640] [152ad188] [1c04] - CALLBACK SET
    [2016-03-05 20:03:00,640] [152ad188] [1c04] - SetRequestAudioChangeCallback SET
    [2016-03-05 20:03:00,642] [152ad188] [1c04] - CTsReaderFilter::Load()
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - OnNewChannel: PAT change detected: -1->1
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - OnNewChannel: New channel found (PAT/PMT/SDT changed)
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - pcr pid: 0x 30
    [2016-03-05 20:03:00,643] [152ad188] [233c] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - pmt pid: 0x 20
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flushing
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - video pid: 0x 30 type: H264 DescriptorData: 0x 0
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flush audio
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - audio pid: 0x 40 language: fra type: DD+
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flush video
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - audio pid: 0x 41 language: qaa type: DD+
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flush subtitle
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - audio pid: 0x 42 language: qad type: DD+
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - Subtitle pid: 0x 50 language: fra type: DVB subtitle 2
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - Subtitle pid: 0x 51 language: fra type: DVB subtitle 2
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - OnNewChannel: OnRequestAudioChange()
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - demux:Wait for Audio stream selection :1
    [2016-03-05 20:03:00,662] [152ad188] [1c04] - SetAudioStream : 0
    [2016-03-05 20:03:00,662] [152ad188] [1c04] - Old Audio -1, New Audio 132
    [2016-03-05 20:03:00,662] [152ad188] [1c04] - demux:Wait for Audio stream selection :0
    [2016-03-05 20:03:00,663] [152ad188] [1c04] - OnNewChannel: New Audio stream type = 0x84
    [2016-03-05 20:03:00,664] [152ad188] [1c04] - demux: E-AC3 header: sampleRate = 48000, channels = 2, bitrate = 128000, parsedChannels = 2
    [2016-03-05 20:03:00,665] [152ad188] [1c04] - demux: E-AC3 good sync = b 77 0 ff 34 85 ff, byteCount = 1024, headerCount = 28
    [2016-03-05 20:03:00,667] [152ad188] [1c04] - DeMultiplexer: First Gop after new PAT, 1920x1080 @ 16:9, 24.832Hz interlaced
    [2016-03-05 20:03:00,667] [152ad188] [1c04] - DeMultiplexer: 30 new video format, 1920x1080 @ 16:9, 24.832Hz interlaced
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - demux:confused:tart() Succeeded : BytesProcessed:524288, DTS/PTS count = 17/17, GOPts = 0
    [2016-03-05 20:03:00,670] [152ad188] [233c] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - UpdateDuration - find pcr
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flushing
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flush audio
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flush video
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flush subtitle
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - UpdateDuration - found startPcr, iterations:1 offset:131072
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - UpdateDuration - found endPcr, iterations:1 offset:259136
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - start:0 end:6876b2e 1217.088989
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - open \\homeserver\mediaserv\record\TVE3\Dossiers surnaturels - 23 - NUMERO 23 (TNT) - 2015-08-29.ts, isTimeshift:0, isUNC:1
    [2016-03-05 20:03:01,238] [152ad188] [1c04] - demux:GetVideoStreamType(), FPS from DTS/PTS = 25.000000, DTSdiff/PTSdiff/MPEG2 = 0.040000/0.040000/0.000000, DTS/PTS count = 17/17
    [2016-03-05 20:03:01,239] [152ad188] [1c04] - audPin:CompleteConnect() ok, filter: LAV Audio Decoder, iPosition: 0
    [2016-03-05 20:03:01,239] [152ad188] [1c04] - audPin:CompleteConnect() DisableSlowPlayDiscontinuity = 0
    [2016-03-05 20:03:01,255] [152ad188] [1c04] - vidPin:CompleteConnect() ok, filter: LAV Video Decoder
    [2016-03-05 20:03:01,360] [152ad188] [1c04] - filt:IID_IMediaSeeking()
    [2016-03-05 20:03:01,404] [152ad188] [1c04] - CTsReaderFilter::pause() - IsTimeShifting = 0 - state = 0
    [2016-03-05 20:03:01,404] [152ad188] [2460] - audPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x2460, GET_TIME_NOW:0x40000308
    [2016-03-05 20:03:01,405] [152ad188] [1c04] - MPAR/Reclock not found
    [2016-03-05 20:03:01,405] [152ad188] [1f20] - vidPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x1f20, GET_TIME_NOW:0x40000309
    [2016-03-05 20:03:01,405] [152ad188] [1c04] - CTsReaderFilter::pause() - END - state = 1
    [2016-03-05 20:03:01,405] [152ad188] [14e8] - CTsReaderFilter:: DurationThread -> busy
    [2016-03-05 20:03:01,405] [152ad188] [1f20] - vidPin:DeliverNewSegment(start 0.000000, stop 461168607232.000000), rate:1.00
    [2016-03-05 20:03:01,405] [152ad188] [14e8] - CTsReaderFilter:: ForcePosnUpdate: 0.000 s
    [2016-03-05 20:03:01,405] [152ad188] [2460] - audPin:DeliverNewSegment(start 0.000000, stop 461168607232.000000), rate:1.00
    [2016-03-05 20:03:01,429] [152ad188] [233c] - demux: E-AC3 good sync = b 77 0 ff 34 85 ff, byteCount = 1024, headerCount = 28
    [2016-03-05 20:03:01,452] [152ad188] [233c] - H.264 I-FRAME found 1.744000
    [2016-03-05 20:03:01,453] [152ad188] [233c] - H.264 First supposed '0' frame found. 1.624000
    [2016-03-05 20:03:01,470] [152ad188] [2460] - Audio Samples : 5, First : 0.170, Last : 0.938, buffThresh : 3, pfLoopDel : 36
    [2016-03-05 20:03:01,470] [152ad188] [2460] - Video Samples : 12, First : 1.624, Last : 2.064, buffThresh : 12
    [2016-03-05 20:03:01,470] [152ad188] [2460] - Compensation : ( AudBackBuff : 0.410 ) Audio pts < Video pts . Add 1.096 sec of extra video comp to start now !...
    [2016-03-05 20:03:01,470] [152ad188] [2460] - demux:Compensation:0.328, Clock on start 0.000 m_rtStart:0
    [2016-03-05 20:03:01,470] [152ad188] [2460] - audPin:CalcAverageFtime m_nMaxAFT: 19, SampDur: 0.153600
    [2016-03-05 20:03:01,471] [152ad188] [2560] - CTsReaderFilter::GetState(), VFW_S_CANT_CUE, m_audioReady 0
    [2016-03-05 20:03:01,471] [152ad188] [2560] - CTsReaderFilter::Run(33922.20) state 1 seeking 0
    [2016-03-05 20:03:01,471] [152ad188] [2560] - CTsReaderFilter::Run(33922.20) state 2 -->done
    [2016-03-05 20:03:01,472] [152ad188] [1f20] - demux:GetVideoStreamType(), FPS from DTS/PTS = 25.000000, DTSdiff/PTSdiff/MPEG2 = 0.040000/0.040000/0.000000, DTS/PTS count = 25/25
    [2016-03-05 20:03:01,472] [152ad188] [1f20] - vidPin: Add pmt and set discontinuity L:1 B:1 fTime:0.671 SampCnt:0
    [2016-03-05 20:03:01,472] [152ad188] [1f20] - Vid/Ref : 1.744, I-frame(00), Compensated = 0.670 ( 0.671 A/V buffers=03/12), Clk : 0.000000, SampCnt 0, stallPt 2.500
    [2016-03-05 20:03:01,473] [152ad188] [2460] - audPin: Set discontinuity L:1 B:0 fTime:1.238 SampCnt:0
    [2016-03-05 20:03:01,473] [152ad188] [2460] - audPin: Add pmt, fTime:1.238 SampCnt:0, Ch:2, Sr:48000
    [2016-03-05 20:03:01,473] [152ad188] [2460] - Aud/Ref : 0.554, Compensated = 0.226 ( 1.238 A/V buffers=03/11), Clk : 0.000000, SampCnt 0, Sleep 1 ms, stallPt 1.500
    [2016-03-05 20:03:01,482] [152ad188] [2460] - Aud/Ref : 0.746, Compensated = 0.418 ( 1.333 A/V buffers=02/11), Clk : 0.001000, SampCnt 1, Sleep 1 ms, stallPt 1.500
    [2016-03-05 20:03:01,492] [152ad188] [2460] - Aud/Ref : 0.938, Compensated = 0.610 ( 1.419 A/V buffers=01/11), Clk : 0.011000, SampCnt 2, Sleep 1 ms, stallPt 1.500
    [2016-03-05 20:03:01,492] [152ad188] [1f20] - Vid/Ref : 1.664, ?-frame(00), Compensated = 0.503 ( 0.492 A/V buffers=00/11), Clk : 0.011000, SampCnt 1, stallPt 2.500
    [2016-03-05 20:03:01,493] [152ad188] [1f20] - Vid/Ref : 1.624, ?-frame(00), Compensated = 0.419 ( 0.407 A/V buffers=00/10), Clk : 0.012000, SampCnt 2, stallPt 2.500
    [2016-03-05 20:03:01,662] [152ad188] [233c] - H.264 2nd GOP found 2.784000
    [2016-03-05 20:03:02,990] [152ad188] [1c04] - CTsReaderFilter::pause() - IsTimeShifting = 0 - state = 2
    [2016-03-05 20:03:02,990] [152ad188] [1c04] - MPAR/Reclock not found
    [2016-03-05 20:03:02,990] [152ad188] [1c04] - CTsReaderFilter::pause() - END - state = 1
    [2016-03-05 20:03:02,990] [152ad188] [1f20] - Vid/Ref : 3.784, ?-frame(00), Compensated = 3.456 ( 2.123 A/V buffers=02/16), Clk : 1.333375, SampCnt 55, stallPt 2.500
    [2016-03-05 20:03:02,992] [152ad188] [1f20] - Vid/Ref : 3.984, ?-frame(00), Compensated = 3.656 ( 2.323 A/V buffers=03/20), Clk : 1.333375, SampCnt 56, stallPt 2.500
    [2016-03-05 20:03:02,994] [152ad188] [1f20] - Vid/Ref : 3.904, ?-frame(00), Compensated = 3.576 ( 2.243 A/V buffers=03/19), Clk : 1.333375, SampCnt 57, stallPt 2.500
    [2016-03-05 20:03:02,995] [152ad188] [1c04] - CTsReaderFilter::confused:top(), state 1
    [2016-03-05 20:03:02,996] [152ad188] [233c] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flushing
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flush audio
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flush video
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flush subtitle
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::confused:top() done, state 0
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - vidPin: UpdateFromSeek, m_rtStart 1.419000, m_dRateSeeking 1.000000
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::--SeekPreStart() End - Stopped
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - audPin: UpdateFromSeek, m_rtStart 1.419000, m_dRateSeeking 1.000000
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::--SeekPreStart() End - Stopped
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - audPin: UpdateFromSeek, m_rtStart 1.419000, m_dRateSeeking 1.000000
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::--SeekPreStart() End - Stopped
    [2016-03-05 20:03:02,998] [152ad188] [14e8] - CTsReaderFilter:: ForcePosnUpdate: 1.419 s
    [2016-03-05 20:03:03,000] [152ad188] [14e8] - CTsReaderFilter:: DurationThread -> idle
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - CTsReaderFilter::dtor
    [2016-03-05 20:03:03,024] [152ad188] [14e8] - CTsReaderFilter::ThreadProc stopped()
    [2016-03-05 20:03:03,024] [152ad188] [233c] - TsAVRT::RevertMMCSThread - AvrtHandle: 5996
    [2016-03-05 20:03:03,024] [152ad188] [233c] - CDeMultiplexer::ThreadProc stopped()
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - audPin:dtor()
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - vidPin:dtor()
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - subPin:dtor()
    [2016-03-05 20:03:03,025] [152ad188] [1c04] - CTsReaderFilter::dtor - finished
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CMemoryBuffer::dtor
    [2016-03-05 20:03:03,027] [ 0] [1c04] - memorybuffer: Clear() 0
    [2016-03-05 20:03:03,027] [ 0] [1c04] - memorybuffer: Clear() done
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CRTSPClient::~CRTSPClient()
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CRTSPClient::confused:hutdown()
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CDeMultiplexer::dtor
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flushing
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flush audio
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flush video
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flush subtitle
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CDeMultiplexer::dtor - finished, instanceCount:0
    [2016-03-05 20:03:03,029] [ 0] [1c04] - TsAVRT::UnloadAVRT() - Unloading AVRT libraries
    [2016-03-05 20:03:03,029] [ 0] [1c04] - CTsDuration - dtor
    [2016-03-05 20:03:03,031] [ 0] [1c04] - CTsDuration - dtor
    [2016-03-05 20:16:58,612] [ 0] [1a1c] - CTsDuration - ctor
    [2016-03-05 20:16:58,612] [ 0] [1a1c] - CTsDuration - ctor
    [2016-03-05 20:16:58,612] [ 0] [1a1c] - TsAVRT::LoadAVRT() - Loading AVRT libraries
    [2016-03-05 20:16:58,612] [ 0] [1a1c] -
     

    Owlsroost

    Development Group
  • Team MediaPortal
  • October 28, 2008
    5,538
    113
    Cambridge
    Country flag
    Here we go for the log : and you are right this time : [2016-03-05 20:03:00,662] [152ad188] [1c04] - SetAudioStream : 0

    [2016-03-05 20:03:00,628] [ 0] [1c04] - =================== New filter instance =========================================
    [2016-03-05 20:03:00,628] [ 0] [1c04] - Logging format: [Date Time] [InstanceID-instanceCount] [ThreadID] Message....
    [2016-03-05 20:03:00,628] [ 0] [1c04] - ==================================================================================
    [2016-03-05 20:03:00,628] [ 0] [1c04] - demux: Start file read thread
    [2016-03-05 20:03:00,628] [ 0] [1c04] - CRTSPClient::CRTSPClient()
    [2016-03-05 20:03:00,628] [ 0] [1c04] - CMemoryBuffer::ctor
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - ------------- v4.2.0.17 ------------- instanceCount:1
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - CTsReaderFilter::ctor
    [2016-03-05 20:03:00,628] [152ad188] [233c] - CDeMultiplexer::ThreadProc start(), threadID:0x233c
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- UseFPSfromDTSPTS = yes
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Buffering delay = 0 ms
    [2016-03-05 20:03:00,628] [152ad188] [233c] - TsAVRT::confused:etMMCSThread - Priority: 0, AvrtHandle: 5996
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Audio delay = 0 ms
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Slow Play = 0 PPM
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- AutoSpeedAdjust = 2
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- LAV_AutoAVSync = 0
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - --- Slow motion video allowed during zapping = yes
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - Wait for seeking to eof - false - constructor
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - SetMediaPosnUpdate : m_pClock invalid
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - CTsReaderFilter::confused:tart duration thread
    [2016-03-05 20:03:00,628] [152ad188] [1c04] - CTsReaderFilter::timeGetTime():0x2059943, m_tGTStartTime:0xc2059943, GET_TIME_NOW:0x40000000, timer res:1 ms
    [2016-03-05 20:03:00,629] [152ad188] [14e8] - CTsReaderFilter::ThreadProc start(), threadID:0x14e8
    [2016-03-05 20:03:00,639] [152ad188] [1c04] - SetRelaxedMode
    [2016-03-05 20:03:00,639] [152ad188] [1c04] - Relaxed discontinuities filtering
    [2016-03-05 20:03:00,640] [152ad188] [1c04] - CALLBACK SET
    [2016-03-05 20:03:00,640] [152ad188] [1c04] - SetRequestAudioChangeCallback SET
    [2016-03-05 20:03:00,642] [152ad188] [1c04] - CTsReaderFilter::Load()
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - OnNewChannel: PAT change detected: -1->1
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - OnNewChannel: New channel found (PAT/PMT/SDT changed)
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - pcr pid: 0x 30
    [2016-03-05 20:03:00,643] [152ad188] [233c] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - pmt pid: 0x 20
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flushing
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - video pid: 0x 30 type: H264 DescriptorData: 0x 0
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flush audio
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - audio pid: 0x 40 language: fra type: DD+
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flush video
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - audio pid: 0x 41 language: qaa type: DD+
    [2016-03-05 20:03:00,643] [152ad188] [233c] - demux:flush subtitle
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - audio pid: 0x 42 language: qad type: DD+
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - Subtitle pid: 0x 50 language: fra type: DVB subtitle 2
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - Subtitle pid: 0x 51 language: fra type: DVB subtitle 2
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - OnNewChannel: OnRequestAudioChange()
    [2016-03-05 20:03:00,643] [152ad188] [1c04] - demux:Wait for Audio stream selection :1
    [2016-03-05 20:03:00,662] [152ad188] [1c04] - SetAudioStream : 0
    [2016-03-05 20:03:00,662] [152ad188] [1c04] - Old Audio -1, New Audio 132
    [2016-03-05 20:03:00,662] [152ad188] [1c04] - demux:Wait for Audio stream selection :0
    [2016-03-05 20:03:00,663] [152ad188] [1c04] - OnNewChannel: New Audio stream type = 0x84
    [2016-03-05 20:03:00,664] [152ad188] [1c04] - demux: E-AC3 header: sampleRate = 48000, channels = 2, bitrate = 128000, parsedChannels = 2
    [2016-03-05 20:03:00,665] [152ad188] [1c04] - demux: E-AC3 good sync = b 77 0 ff 34 85 ff, byteCount = 1024, headerCount = 28
    [2016-03-05 20:03:00,667] [152ad188] [1c04] - DeMultiplexer: First Gop after new PAT, 1920x1080 @ 16:9, 24.832Hz interlaced
    [2016-03-05 20:03:00,667] [152ad188] [1c04] - DeMultiplexer: 30 new video format, 1920x1080 @ 16:9, 24.832Hz interlaced
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - demux:confused:tart() Succeeded : BytesProcessed:524288, DTS/PTS count = 17/17, GOPts = 0
    [2016-03-05 20:03:00,670] [152ad188] [233c] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - UpdateDuration - find pcr
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flushing
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flush audio
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flush video
    [2016-03-05 20:03:00,670] [152ad188] [233c] - demux:flush subtitle
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - UpdateDuration - found startPcr, iterations:1 offset:131072
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - UpdateDuration - found endPcr, iterations:1 offset:259136
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - start:0 end:6876b2e 1217.088989
    [2016-03-05 20:03:00,670] [152ad188] [1c04] - open \\homeserver\mediaserv\record\TVE3\Dossiers surnaturels - 23 - NUMERO 23 (TNT) - 2015-08-29.ts, isTimeshift:0, isUNC:1
    [2016-03-05 20:03:01,238] [152ad188] [1c04] - demux:GetVideoStreamType(), FPS from DTS/PTS = 25.000000, DTSdiff/PTSdiff/MPEG2 = 0.040000/0.040000/0.000000, DTS/PTS count = 17/17
    [2016-03-05 20:03:01,239] [152ad188] [1c04] - audPin:CompleteConnect() ok, filter: LAV Audio Decoder, iPosition: 0
    [2016-03-05 20:03:01,239] [152ad188] [1c04] - audPin:CompleteConnect() DisableSlowPlayDiscontinuity = 0
    [2016-03-05 20:03:01,255] [152ad188] [1c04] - vidPin:CompleteConnect() ok, filter: LAV Video Decoder
    [2016-03-05 20:03:01,360] [152ad188] [1c04] - filt:IID_IMediaSeeking()
    [2016-03-05 20:03:01,404] [152ad188] [1c04] - CTsReaderFilter::pause() - IsTimeShifting = 0 - state = 0
    [2016-03-05 20:03:01,404] [152ad188] [2460] - audPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x2460, GET_TIME_NOW:0x40000308
    [2016-03-05 20:03:01,405] [152ad188] [1c04] - MPAR/Reclock not found
    [2016-03-05 20:03:01,405] [152ad188] [1f20] - vidPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x1f20, GET_TIME_NOW:0x40000309
    [2016-03-05 20:03:01,405] [152ad188] [1c04] - CTsReaderFilter::pause() - END - state = 1
    [2016-03-05 20:03:01,405] [152ad188] [14e8] - CTsReaderFilter:: DurationThread -> busy
    [2016-03-05 20:03:01,405] [152ad188] [1f20] - vidPin:DeliverNewSegment(start 0.000000, stop 461168607232.000000), rate:1.00
    [2016-03-05 20:03:01,405] [152ad188] [14e8] - CTsReaderFilter:: ForcePosnUpdate: 0.000 s
    [2016-03-05 20:03:01,405] [152ad188] [2460] - audPin:DeliverNewSegment(start 0.000000, stop 461168607232.000000), rate:1.00
    [2016-03-05 20:03:01,429] [152ad188] [233c] - demux: E-AC3 good sync = b 77 0 ff 34 85 ff, byteCount = 1024, headerCount = 28
    [2016-03-05 20:03:01,452] [152ad188] [233c] - H.264 I-FRAME found 1.744000
    [2016-03-05 20:03:01,453] [152ad188] [233c] - H.264 First supposed '0' frame found. 1.624000
    [2016-03-05 20:03:01,470] [152ad188] [2460] - Audio Samples : 5, First : 0.170, Last : 0.938, buffThresh : 3, pfLoopDel : 36
    [2016-03-05 20:03:01,470] [152ad188] [2460] - Video Samples : 12, First : 1.624, Last : 2.064, buffThresh : 12
    [2016-03-05 20:03:01,470] [152ad188] [2460] - Compensation : ( AudBackBuff : 0.410 ) Audio pts < Video pts . Add 1.096 sec of extra video comp to start now !...
    [2016-03-05 20:03:01,470] [152ad188] [2460] - demux:Compensation:0.328, Clock on start 0.000 m_rtStart:0
    [2016-03-05 20:03:01,470] [152ad188] [2460] - audPin:CalcAverageFtime m_nMaxAFT: 19, SampDur: 0.153600
    [2016-03-05 20:03:01,471] [152ad188] [2560] - CTsReaderFilter::GetState(), VFW_S_CANT_CUE, m_audioReady 0
    [2016-03-05 20:03:01,471] [152ad188] [2560] - CTsReaderFilter::Run(33922.20) state 1 seeking 0
    [2016-03-05 20:03:01,471] [152ad188] [2560] - CTsReaderFilter::Run(33922.20) state 2 -->done
    [2016-03-05 20:03:01,472] [152ad188] [1f20] - demux:GetVideoStreamType(), FPS from DTS/PTS = 25.000000, DTSdiff/PTSdiff/MPEG2 = 0.040000/0.040000/0.000000, DTS/PTS count = 25/25
    [2016-03-05 20:03:01,472] [152ad188] [1f20] - vidPin: Add pmt and set discontinuity L:1 B:1 fTime:0.671 SampCnt:0
    [2016-03-05 20:03:01,472] [152ad188] [1f20] - Vid/Ref : 1.744, I-frame(00), Compensated = 0.670 ( 0.671 A/V buffers=03/12), Clk : 0.000000, SampCnt 0, stallPt 2.500
    [2016-03-05 20:03:01,473] [152ad188] [2460] - audPin: Set discontinuity L:1 B:0 fTime:1.238 SampCnt:0
    [2016-03-05 20:03:01,473] [152ad188] [2460] - audPin: Add pmt, fTime:1.238 SampCnt:0, Ch:2, Sr:48000
    [2016-03-05 20:03:01,473] [152ad188] [2460] - Aud/Ref : 0.554, Compensated = 0.226 ( 1.238 A/V buffers=03/11), Clk : 0.000000, SampCnt 0, Sleep 1 ms, stallPt 1.500
    [2016-03-05 20:03:01,482] [152ad188] [2460] - Aud/Ref : 0.746, Compensated = 0.418 ( 1.333 A/V buffers=02/11), Clk : 0.001000, SampCnt 1, Sleep 1 ms, stallPt 1.500
    [2016-03-05 20:03:01,492] [152ad188] [2460] - Aud/Ref : 0.938, Compensated = 0.610 ( 1.419 A/V buffers=01/11), Clk : 0.011000, SampCnt 2, Sleep 1 ms, stallPt 1.500
    [2016-03-05 20:03:01,492] [152ad188] [1f20] - Vid/Ref : 1.664, ?-frame(00), Compensated = 0.503 ( 0.492 A/V buffers=00/11), Clk : 0.011000, SampCnt 1, stallPt 2.500
    [2016-03-05 20:03:01,493] [152ad188] [1f20] - Vid/Ref : 1.624, ?-frame(00), Compensated = 0.419 ( 0.407 A/V buffers=00/10), Clk : 0.012000, SampCnt 2, stallPt 2.500
    [2016-03-05 20:03:01,662] [152ad188] [233c] - H.264 2nd GOP found 2.784000
    [2016-03-05 20:03:02,990] [152ad188] [1c04] - CTsReaderFilter::pause() - IsTimeShifting = 0 - state = 2
    [2016-03-05 20:03:02,990] [152ad188] [1c04] - MPAR/Reclock not found
    [2016-03-05 20:03:02,990] [152ad188] [1c04] - CTsReaderFilter::pause() - END - state = 1
    [2016-03-05 20:03:02,990] [152ad188] [1f20] - Vid/Ref : 3.784, ?-frame(00), Compensated = 3.456 ( 2.123 A/V buffers=02/16), Clk : 1.333375, SampCnt 55, stallPt 2.500
    [2016-03-05 20:03:02,992] [152ad188] [1f20] - Vid/Ref : 3.984, ?-frame(00), Compensated = 3.656 ( 2.323 A/V buffers=03/20), Clk : 1.333375, SampCnt 56, stallPt 2.500
    [2016-03-05 20:03:02,994] [152ad188] [1f20] - Vid/Ref : 3.904, ?-frame(00), Compensated = 3.576 ( 2.243 A/V buffers=03/19), Clk : 1.333375, SampCnt 57, stallPt 2.500
    [2016-03-05 20:03:02,995] [152ad188] [1c04] - CTsReaderFilter::confused:top(), state 1
    [2016-03-05 20:03:02,996] [152ad188] [233c] - CDeMultiplexer::ThreadProc - Flush
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flushing
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flush audio
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flush video
    [2016-03-05 20:03:02,996] [152ad188] [233c] - demux:flush subtitle
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::confused:top() done, state 0
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - vidPin: UpdateFromSeek, m_rtStart 1.419000, m_dRateSeeking 1.000000
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::--SeekPreStart() End - Stopped
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - audPin: UpdateFromSeek, m_rtStart 1.419000, m_dRateSeeking 1.000000
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::--SeekPreStart() End - Stopped
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - audPin: UpdateFromSeek, m_rtStart 1.419000, m_dRateSeeking 1.000000
    [2016-03-05 20:03:02,998] [152ad188] [1c04] - CTsReaderFilter::--SeekPreStart() End - Stopped
    [2016-03-05 20:03:02,998] [152ad188] [14e8] - CTsReaderFilter:: ForcePosnUpdate: 1.419 s
    [2016-03-05 20:03:03,000] [152ad188] [14e8] - CTsReaderFilter:: DurationThread -> idle
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - CTsReaderFilter::dtor
    [2016-03-05 20:03:03,024] [152ad188] [14e8] - CTsReaderFilter::ThreadProc stopped()
    [2016-03-05 20:03:03,024] [152ad188] [233c] - TsAVRT::RevertMMCSThread - AvrtHandle: 5996
    [2016-03-05 20:03:03,024] [152ad188] [233c] - CDeMultiplexer::ThreadProc stopped()
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - audPin:dtor()
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - vidPin:dtor()
    [2016-03-05 20:03:03,024] [152ad188] [1c04] - subPin:dtor()
    [2016-03-05 20:03:03,025] [152ad188] [1c04] - CTsReaderFilter::dtor - finished
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CMemoryBuffer::dtor
    [2016-03-05 20:03:03,027] [ 0] [1c04] - memorybuffer: Clear() 0
    [2016-03-05 20:03:03,027] [ 0] [1c04] - memorybuffer: Clear() done
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CRTSPClient::~CRTSPClient()
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CRTSPClient::confused:hutdown()
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CDeMultiplexer::dtor
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flushing
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flush audio
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flush video
    [2016-03-05 20:03:03,027] [ 0] [1c04] - demux:flush subtitle
    [2016-03-05 20:03:03,027] [ 0] [1c04] - CDeMultiplexer::dtor - finished, instanceCount:0
    [2016-03-05 20:03:03,029] [ 0] [1c04] - TsAVRT::UnloadAVRT() - Unloading AVRT libraries
    [2016-03-05 20:03:03,029] [ 0] [1c04] - CTsDuration - dtor
    [2016-03-05 20:03:03,031] [ 0] [1c04] - CTsDuration - dtor
    [2016-03-05 20:16:58,612] [ 0] [1a1c] - CTsDuration - ctor
    [2016-03-05 20:16:58,612] [ 0] [1a1c] - CTsDuration - ctor
    [2016-03-05 20:16:58,612] [ 0] [1a1c] - TsAVRT::LoadAVRT() - Loading AVRT libraries
    [2016-03-05 20:16:58,612] [ 0] [1a1c] -
    Yes, that looks OK.
     

    Sebastiii

    Development Group
  • Team MediaPortal
  • November 12, 2007
    16,341
    113
    France
    Country flag
    @yartat

    If can do that :

    C#:
      public static int CurrentAudioStream
        {
          get
          {
            if (_player == null)
            {
              return 0;
            }
            return _player.CurrentAudioStream;
          }
          set
          {
            if (_player != null)
            {
              if (_player.CurrentAudioStream != value)
              {
                var oldStream = _player.CurrentAudio;
                _player.CurrentAudioStream = value;
                OnStreamChanged(StreamKind.Audio, oldStream, _player.CurrentAudio, _player);
              }
              else
              {
                _player.CurrentAudioStream = value;
              }
            }
          }
        }
     

    Users Who Are Viewing This Thread (Users: 0, Guests: 1)

    Top Bottom