[2013-08-27 11:50:54,358] [ 0] [ ddc] - [2013-08-27 11:50:54,358] [ 0] [ ddc] - =================== New filter instance =========================== [2013-08-27 11:50:54,358] [ 0] [ ddc] - Logging format: [Date Time] [InstanceID] [ThreadID] Message.... [2013-08-27 11:50:54,358] [ 0] [ ddc] - =================================================================== [2013-08-27 11:50:54,358] [ 0] [ ddc] - demux: Start file read thread [2013-08-27 11:50:54,359] [ 0] [ ddc] - CRTSPClient::CRTSPClient() [2013-08-27 11:50:54,359] [ 0] [ ddc] - CMemoryBuffer::ctor [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - ------------- v3.0.74.0 ------------- [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CTsReaderFilter::ctor [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CRTSPClient::Initialize() [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CRTSPClient::createClient() [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - ----- UseFPSfromDTSPTS ----- [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - --- Buffering delay = 300 ms [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - Slow motion video allowed during zapping [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - Wait for seeking to eof - false - constructor [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CTsReaderFilter::Start duration thread [2013-08-27 11:50:54,359] [18c03bf0] [ c14] - CDeMultiplexer::ThreadProc start(), threadID:0xc14 [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CTsReaderFilter::timeGetTime():0xa554eb, m_tGTStartTime:0xc0a554ea, GET_TIME_NOW:0x40000001 [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - SetRelaxedMode [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - Normal discontinuities filtering [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CALLBACK SET [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - SetRequestAudioChangeCallback SET [2013-08-27 11:50:54,359] [18c03bf0] [ ddc] - CTsReaderFilter::Load() [2013-08-27 11:50:54,360] [18c03bf0] [1474] - CTsReaderFilter::ThreadProc start(), threadID:0x1474 [2013-08-27 11:50:54,363] [18c03bf0] [ ddc] - FileReader::CloseFile() no open file [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - OnNewChannel: PAT change detected: -1->0 [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - New channel found (PAT/PMT/SDT changed) [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - pcr pid: 1ff [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - pmt pid: 42 [2013-08-27 11:50:54,365] [18c03bf0] [ c14] - CDeMultiplexer::ThreadProc - Flush [2013-08-27 11:50:54,365] [18c03bf0] [ c14] - demux:flushing [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - video pid: 1ff type: MPEG2 [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - audio pid: 120 language: dut type: MPEG2 - audio [2013-08-27 11:50:54,365] [18c03bf0] [ c14] - demux:flush audio [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - Setting initial audio index to : 0 [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - OnNewChannel: OnRequestAudioChange() [2013-08-27 11:50:54,365] [18c03bf0] [ ddc] - demux:Wait for Audio stream selection :1 [2013-08-27 11:50:54,365] [18c03bf0] [ c14] - demux:flush video [2013-08-27 11:50:54,365] [18c03bf0] [ c14] - demux:flush subtitle [2013-08-27 11:50:54,367] [18c03bf0] [ ddc] - SetAudioStream : 0 [2013-08-27 11:50:54,367] [18c03bf0] [ ddc] - Old Audio -1, New Audio 4 [2013-08-27 11:50:54,367] [18c03bf0] [ ddc] - demux:Wait for Audio stream selection :0 [2013-08-27 11:50:54,367] [18c03bf0] [ ddc] - OnNewChannel: New Audio 4 [2013-08-27 11:50:54,658] [18c03bf0] [ ddc] - DeMultiplexer: First Gop after new PAT, 704x576 @ 16:9, 25.000Hz interlaced [2013-08-27 11:50:54,658] [18c03bf0] [ ddc] - DeMultiplexer: triggering OnVideoFormatChanged [2013-08-27 11:50:54,658] [18c03bf0] [ ddc] - vidPin:SetAddPMT() [2013-08-27 11:50:54,658] [18c03bf0] [ ddc] - DeMultiplexer: 1ff video format changed, 704x576 @ 16:9, 25.000Hz interlaced [2013-08-27 11:50:54,658] [18c03bf0] [ ddc] - vidPin:SetAddPMT() [2013-08-27 11:50:54,661] [18c03bf0] [ c14] - CDeMultiplexer::ThreadProc - Flush [2013-08-27 11:50:54,661] [18c03bf0] [ c14] - demux:flushing [2013-08-27 11:50:54,661] [18c03bf0] [ ddc] - demux:Start() end1 BytesProcessed:244400, DTS/PTS count = 5/16, GOPts = 0 [2013-08-27 11:50:54,661] [18c03bf0] [ c14] - demux:flush audio [2013-08-27 11:50:54,661] [18c03bf0] [ ddc] - UpdateDuration - find pcr [2013-08-27 11:50:54,661] [18c03bf0] [ c14] - demux:flush video [2013-08-27 11:50:54,661] [18c03bf0] [ c14] - demux:flush subtitle [2013-08-27 11:50:54,664] [18c03bf0] [ ddc] - FileReader::CloseFile() no open file [2013-08-27 11:50:54,668] [18c03bf0] [ ddc] - UpdateDuration - found startPcr, iterations:1 offset:131072 [2013-08-27 11:50:54,673] [18c03bf0] [ ddc] - UpdateDuration - found endPcr, iterations:1 offset:259136 [2013-08-27 11:50:54,676] [18c03bf0] [ ddc] - start:267e end:d196 0.486000 [2013-08-27 11:50:54,677] [18c03bf0] [ ddc] - open \\MEDIA-SERVER\Timeshift Argus TV\Card3-1-0.ts.tsbuffer, isTimeshift:1, isUNC:1 [2013-08-27 11:50:54,894] [18c03bf0] [ ddc] - demux:GetVideoStreamType(), FPS from DTS/PTS = 25.000000, DTSdiff/PTSdiff/MPEG2 = 0.120000/0.040000/0.040000, DTS/PTS count = 5/16 [2013-08-27 11:50:54,894] [18c03bf0] [ ddc] - audPin:CompleteConnect() ok, filter: LAV Audio Decoder [2013-08-27 11:50:54,937] [18c03bf0] [ ddc] - vidPin:CompleteConnect() ok, filter: LAV Video Decoder [2013-08-27 11:50:54,940] [18c03bf0] [ ddc] - subPin:CompleteConnect() ok, filter: MediaPortal DVBSub3 [2013-08-27 11:50:54,940] [18c03bf0] [ ddc] - DVBSub3 interface OK [2013-08-27 11:50:54,941] [18c03bf0] [ ddc] - SUCCESS [2013-08-27 11:50:54,941] [18c03bf0] [ ddc] - SUCCESS [2013-08-27 11:50:54,966] [18c03bf0] [ ddc] - CTsReaderFilter::Pause() - IsTimeShifting = 1 - state = 0 [2013-08-27 11:50:54,966] [18c03bf0] [ 5c8] - audPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x5c8, GET_TIME_NOW:0x40000260 [2013-08-27 11:50:54,967] [18c03bf0] [ 8f8] - vidPin:OnThreadStartPlay(0.000000), rate:1.00, threadID:0x8f8, GET_TIME_NOW:0x40000261 [2013-08-27 11:50:54,967] [18c03bf0] [ 8f8] - vidPin:DeliverNewSegment(start 0.000000, stop 461168601842.738770), rate:1.00 [2013-08-27 11:50:54,967] [18c03bf0] [ 5c8] - audPin:DeliverNewSegment(start 0.000000, stop 461168601842.738770), rate:1.00 [2013-08-27 11:50:54,967] [18c03bf0] [ 5c8] - demux:pDVBSubtitleFilter->SetTimeCompensation [2013-08-27 11:50:54,968] [18c03bf0] [ ddc] - CTsReaderFilter::Pause() - END - state = 1 [2013-08-27 11:50:54,968] [18c03bf0] [ 9b0] - subPin:OnThreadStartPlay(0.000000) [2013-08-27 11:50:54,968] [18c03bf0] [1474] - CTsReaderFilter:: DurationThread -> busy [2013-08-27 11:50:54,968] [18c03bf0] [ ddc] - filt:IID_IMediaSeeking() [2013-08-27 11:50:54,968] [18c03bf0] [1474] - CTsReaderFilter:: ForcePosnUpdate: 0.000 s [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - vidPin: UpdateFromSeek, m_rtStart 0.486000, m_dRateSeeking 1.000000 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - CTsReaderFilter::--SeekPreStart()-- LiveTv : 1, TimeShifting: 1 0.486 ( Abs 0.486000 / 0.596159 )- isSamePosn: 0, OnZap: 0, Force 0, ForceRC 0, Media changing 0 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - Zap to File Seek : 614 mS ( 0.486000 / 0.486000 ) LiveTv : 1, Seek : 0 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - CTsReaderFilter::--SeekPreStart() End - 0 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - audPin: UpdateFromSeek, m_rtStart 0.486000, m_dRateSeeking 1.000000 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - CTsReaderFilter::--SeekPreStart()-- No new seek 0.486000 ( Abs 0.486000 / 0.596159 ) - isSamePosn: 1, OnZap: 0, Force 0, Media changing: 0 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - audPin: UpdateFromSeek, m_rtStart 0.486000, m_dRateSeeking 1.000000 [2013-08-27 11:50:54,973] [18c03bf0] [ ddc] - CTsReaderFilter::--SeekPreStart()-- No new seek 0.486000 ( Abs 0.486000 / 0.596159 ) - isSamePosn: 1, OnZap: 0, Force 0, Media changing: 0 [2013-08-27 11:50:55,032] [18c03bf0] [ c14] - MPEG I-FRAME found 0.858000 [2013-08-27 11:50:55,045] [18c03bf0] [ c14] - MPEG First '0' frame found. 0.778000 [2013-08-27 11:50:55,127] [18c03bf0] [ 5c8] - Audio Samples : 6, First : 0.140, Last : 0.860, buffThresh : 5 [2013-08-27 11:50:55,127] [18c03bf0] [ 5c8] - Video Samples : 19, First : 0.778, Last : 1.498, buffThresh : 13 [2013-08-27 11:50:55,127] [18c03bf0] [ 5c8] - Compensation : ( Rnd : 0 mS ) Audio pts ahead Video pts . Add 0.500 sec of extra video comp to start now !... [2013-08-27 11:50:55,127] [18c03bf0] [ 5c8] - demux:Compensation:-0.508, Clock on start 0.000 m_rtStart:486 [2013-08-27 11:50:55,127] [18c03bf0] [ 5c8] - demux:pDVBSubtitleFilter->SetTimeCompensation [2013-08-27 11:50:55,128] [18c03bf0] [ 5c8] - audPin: Add pmt and set discontinuity L:1 B:0 fTime:0.306 SampCnt:0 [2013-08-27 11:50:55,128] [18c03bf0] [ 5c8] - Aud/Ref : 0.284, Compensated = 0.306 ( 0.306 A/V buffers=05/19), Clk : 0.000000, SampCnt 0, Sleep 1 ms, stallPt 1.500 [2013-08-27 11:50:55,128] [18c03bf0] [ 5c8] - demux:GetVideoStreamType(), FPS from DTS/PTS = 25.000000, DTSdiff/PTSdiff/MPEG2 = 0.080000/0.040000/0.040000, DTS/PTS count = 12/31 [2013-08-27 11:50:55,129] [18c03bf0] [ 8f8] - vidPin: Add pmt and set discontinuity L:1 B:1 fTime:0.570 SampCnt:0 [2013-08-27 11:50:55,129] [18c03bf0] [ 8f8] - Vid/Ref : 0.858, I-frame(02), Compensated = 0.570 ( 0.570 A/V buffers=04/19), Clk : 0.000000, SampCnt 0, stallPt 2.500 [2013-08-27 11:50:55,129] [18c03bf0] [ a34] - CTsReaderFilter::Run(10835.96) state 1 seeking 0 [2013-08-27 11:50:55,129] [18c03bf0] [ a34] - Run() - Elapsed time from pause to Audio/Video ( total zapping time ) : 770 mS [2013-08-27 11:50:55,129] [18c03bf0] [ a34] - CTsReaderFilter::Run(10835.96) state 2 -->done [2013-08-27 11:50:55,131] [18c03bf0] [ 8f8] - Vid/Ref : 0.778, B-frame(00), Compensated = 0.450 ( 0.448 A/V buffers=04/18), Clk : 0.002000, SampCnt 1, stallPt 2.500 [2013-08-27 11:50:55,134] [18c03bf0] [ 8f8] - Vid/Ref : 0.818, B-frame(01), Compensated = 0.510 ( 0.505 A/V buffers=04/17), Clk : 0.005000, SampCnt 2, stallPt 2.500 [2013-08-27 11:50:55,135] [18c03bf0] [ 8f8] - Vid/Ref : 0.978, P-frame(05), Compensated = 0.750 ( 0.744 A/V buffers=04/16), Clk : 0.006000, SampCnt 3, stallPt 2.500 [2013-08-27 11:50:55,136] [18c03bf0] [ 5c8] - Aud/Ref : 0.428, Compensated = 0.450 ( 0.443 A/V buffers=04/15), Clk : 0.007000, SampCnt 1, Sleep 1 ms, stallPt 1.500 [2013-08-27 11:50:55,141] [18c03bf0] [ 5c8] - Aud/Ref : 0.572, Compensated = 0.594 ( 0.582 A/V buffers=03/14), Clk : 0.012000, SampCnt 2, Sleep 1 ms, stallPt 1.500 [2013-08-27 11:50:55,152] [18c03bf0] [ 5c8] - Aud/Ref : 0.716, Compensated = 0.738 ( 0.715 A/V buffers=02/11), Clk : 0.023000, SampCnt 3, Sleep 1 ms, stallPt 1.500 [2013-08-27 11:50:55,367] [18c03bf0] [ c14] - Demux : Video to render 1.371 Sec [2013-08-27 11:50:55,367] [18c03bf0] [ c14] - Demux : Audio to render 0.907 Sec [2013-08-27 11:51:01,696] [18c03bf0] [ c14] - Demux : Video to render 1.326 Sec [2013-08-27 11:51:04,253] [18c03bf0] [ c14] - Demux : Video to render 1.290 Sec [2013-08-27 11:51:07,734] [18c03bf0] [ c14] - Demux : Video to render 1.289 Sec [2013-08-27 11:51:08,174] [18c03bf0] [ c14] - Demux : Video to render 1.209 Sec [2013-08-27 11:51:09,813] [18c03bf0] [ c14] - Demux : Audio to render 0.906 Sec [2013-08-27 11:51:35,332] [18c03bf0] [ c14] - Demux : Audio to render 0.875 Sec [2013-08-27 11:51:36,772] [18c03bf0] [ c14] - Demux : Audio to render 0.875 Sec [2013-08-27 11:51:41,535] [18c03bf0] [ c14] - Demux : Video to render 1.089 Sec [2013-08-27 11:51:41,734] [18c03bf0] [ c14] - Demux : Video to render 1.050 Sec [2013-08-27 11:51:42,733] [18c03bf0] [ c14] - Demux : Audio to render 0.819 Sec [2013-08-27 11:51:43,174] [18c03bf0] [ c14] - Demux : Audio to render 0.810 Sec [2013-08-27 11:51:43,335] [18c03bf0] [ c14] - Demux : Audio to render 0.793 Sec [2013-08-27 11:52:22,096] [18c03bf0] [ c14] - Demux : Audio to render 0.769 Sec [2013-08-27 11:52:49,177] [18c03bf0] [ c14] - Demux : Audio to render 0.762 Sec [2013-08-27 11:52:51,083] [18c03bf0] [ c14] - Demux : Audio to render 0.728 Sec [2013-08-27 11:53:03,194] [18c03bf0] [ c14] - Demux : Audio to render 0.712 Sec [2013-08-27 11:53:03,485] [18c03bf0] [ c14] - Demux : Audio to render 0.709 Sec [2013-08-27 11:53:03,656] [18c03bf0] [ c14] - Demux : Audio to render 0.682 Sec [2013-08-27 11:53:04,136] [18c03bf0] [ c14] - Demux : Audio to render 0.634 Sec [2013-08-27 11:53:05,296] [18c03bf0] [ c14] - Demux : Audio to render 0.627 Sec [2013-08-27 11:53:05,296] [18c03bf0] [ c14] - Demux : Video to render 0.970 Sec [2013-08-27 11:53:07,138] [18c03bf0] [ c14] - Demux : Video to render 0.929 Sec [2013-08-27 11:53:54,258] [18c03bf0] [ c14] - Demux : Audio to render 0.626 Sec [2013-08-27 11:53:54,703] [18c03bf0] [ c14] - Demux : Audio to render 0.613 Sec [2013-08-27 11:54:00,804] [18c03bf0] [ c14] - Demux : Audio to render 0.561 Sec [2013-08-27 11:54:01,990] [18c03bf0] [ c14] - Demux : Audio to render 0.527 Sec [2013-08-27 11:54:05,911] [18c03bf0] [ c14] - Demux : Audio to render 0.494 Sec [2013-08-27 11:54:06,458] [18c03bf0] [ c14] - Demux : Video to render 0.851 Sec [2013-08-27 11:55:00,700] [18c03bf0] [ c14] - Demux : Audio to render 0.427 Sec [2013-08-27 11:55:14,782] [18c03bf0] [ 8f8] - vidPin : Video to render late= 0.289 [2013-08-27 11:55:21,301] [18c03bf0] [ c14] - Demux : Audio to render 0.418 Sec [2013-08-27 11:55:23,307] [18c03bf0] [ c14] - Video Continuity error... 2 ( prev 0 ) [2013-08-27 11:55:23,499] [18c03bf0] [ c14] - Video Continuity error... 1 ( prev f ) [2013-08-27 11:55:23,694] [18c03bf0] [ c14] - Video Continuity error... 6 ( prev 4 ) [2013-08-27 11:55:24,014] [18c03bf0] [ 8f8] - vidPin: Set discontinuity L:0 B:1 fTime:0.377 SampCnt:6710 [2013-08-27 11:55:24,594] [18c03bf0] [ 8f8] - vidPin: Set discontinuity L:0 B:1 fTime:0.437 SampCnt:6724 [2013-08-27 11:55:24,634] [18c03bf0] [ 8f8] - vidPin: Set discontinuity L:0 B:1 fTime:0.477 SampCnt:6725 [2013-08-27 11:57:07,313] [18c03bf0] [ c14] - Demux : Audio to render 0.394 Sec [2013-08-27 11:57:08,583] [18c03bf0] [ c14] - Demux : Video to render 0.812 Sec