The VideoLAN Forums

Posted: **23 May 2012 15:48**

Hello,

could you please help me with the following issue?

I have the following setup:

RTSP stream over LAN (100MB, completely free).

RTSP Server: Windows XP, VLC 2.01 - VOD (input file: AVI - x264 720x576 25FPS + AAC Stereo 32kHz)
RTSP Client: Windows 7, VLC 2.0.1

VLC(Client) starts playing video, but after ~4s,upon reception if the first RTCP Sender report,
live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP()
main error: ES_OUT_RESET_PCR called
stops the video, performs the full buffering, and then continues playing till the the end of the stream.

It happens with other rtsp servers as well. How can I avoid this problem?

Thank you in advance,
Max

Code: Select all

main debug: processing request item: rtsp://192.168.150.241:554/Birds, node: Playlist, skip: 0
main debug: resyncing on rtsp://192.168.150.241:554/Birds
main debug: rtsp://192.168.150.241:554/Birds is at 0
main debug: starting playback of the new playlist item
main debug: creating new input thread
main debug: Creating an input for 'rtsp://192.168.150.241:554/Birds'
main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\MAXIMW~1.CER\AppData\Local\Temp'
main debug: `rtsp://192.168.150.241:554/Birds' gives access `rtsp' demux `' path `192.168.150.241:554/Birds'
main debug: creating demux: access='rtsp' demux='' location='192.168.150.241:554/Birds' file='\\192.168.150.241:554\Birds'
main debug: looking for access_demux module: 1 candidate
live555 debug: version 2011.12.23
qt4 debug: IM: Setting an input
live555 debug: RTP subsession 'audio/MPEG4-GENERIC'
main debug: selecting program id=0
live555 debug: RTP subsession 'video/H264'
live555 debug: setup start: 0.000000 stop:158.000000
live555 debug: We have a timeout of 60 seconds
live555 debug: spawned timeout thread
live555 debug: play start: 0.000000 stop:158.000000
main debug: using access_demux module "live555"
main debug: TIMER module_need() : 385.886 ms - Total 385.886 ms / 1 intvls (Avg 385.886 ms)
main debug: looking for decoder module: 31 candidates
main debug: using decoder module "faad"
main debug: TIMER module_need() : 0.489 ms - Total 0.489 ms / 1 intvls (Avg 0.489 ms)
main debug: looking for decoder module: 31 candidates
avcodec debug: libavcodec already initialized
avcodec debug: trying to use direct rendering
avcodec debug: allowing 3 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 3 threads
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 2.347 ms - Total 2.347 ms / 1 intvls (Avg 2.347 ms)
main debug: looking for packetizer module: 21 candidates
main debug: using packetizer module "packetizer_h264"
main debug: TIMER module_need() : 0.221 ms - Total 0.221 ms / 1 intvls (Avg 0.221 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\maximw.CERTIFICALL\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 4.126 ms - Total 4.126 ms / 1 intvls (Avg 4.126 ms)
main debug: `rtsp://192.168.150.241:554/Birds' successfully opened
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 0%
packetizer_h264 debug: found NAL_SPS (sps_id=0)
packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
faad warning: decoded zero sample
main debug: Buffering 3%
main debug: recycling audio output
main debug: looking for audio output module: 2 candidates
aout_directx debug: Opening DirectSound Audio Output
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Speakers (High Definition Audio Device)
aout_directx debug: using device: Speakers (High Definition Audio Device)
main debug: Buffering 4%
main debug: Buffering 4%
main debug: Buffering 4%
main debug: Buffering 4%
main debug: Buffering 4%
main debug: Buffering 4%
main debug: looking for text renderer module: 2 candidates
freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 1.681 ms - Total 1.681 ms / 1 intvls (Avg 1.681 ms)
main debug: looking for video filter2 module: 18 candidates
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: RGBA with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 0.827 ms - Total 0.827 ms / 1 intvls (Avg 0.827 ms)
main debug: looking for video filter2 module: 18 candidates
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 0.162 ms - Total 0.162 ms / 1 intvls (Avg 0.162 ms)
main debug: Deinterlacing available
main debug: deinterlace 0, mode blend, is_needed 0
main debug: Opening vout display wrapper
main debug: looking for vout display module: 6 candidates
direct3d debug: creating Vout EventThread
direct3d debug: DirectXCreateWindow
main debug: looking for vout window hwnd module: 2 candidates
qt4 debug: requesting video...
main debug: Buffering 6%
aout_directx debug: device supports 2 channels (DEFAULT!)
aout_directx debug: device supports 1 channel
aout_directx debug: Windows says your SpeakerConfig is stereo
aout_directx debug: creating DirectSoundThread
main debug: using audio output module "aout_directx"
main debug: TIMER module_need() : 42.877 ms - Total 42.877 ms / 1 intvls (Avg 42.877 ms)
main debug: output 's16l' 32000 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'f32l' 32000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'s16l' 32000 Hz->32000 Hz Stereo->Stereo
aout_directx debug: DirectSoundThread ready
main debug: looking for audio filter module: 14 candidates
audio_format debug: f32l->s16l, bits per sample: 32->16
main debug: using audio filter module "audio_format"
main debug: TIMER module_need() : 0.192 ms - Total 0.192 ms / 1 intvls (Avg 0.192 ms)
main debug: conversion pipeline completed
main debug: looking for audio mixer module: 2 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 0.105 ms - Total 0.105 ms / 1 intvls (Avg 0.105 ms)
main debug: input 'f32l' 32000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 32000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 960.000 stride_in, 960 stride_out, 768 standing, 192 overlap, 448 search, 1600 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.147 ms - Total 0.147 ms / 1 intvls (Avg 0.147 ms)
main debug: filter(s) 'f32l'->'f32l' 32000 Hz->32000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 32000 Hz->32000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 35200 Hz->32000 Hz Stereo->Stereo
main debug: looking for audio filter module: 14 candidates
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 0.180 ms - Total 0.180 ms / 1 intvls (Avg 0.180 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
main debug: Buffering 8%
main debug: Buffering 9%
qt4 debug: Video was requested 0, 0
main debug: using vout window hwnd module "qt4"
main debug: TIMER module_need() : 58.169 ms - Total 58.169 ms / 1 intvls (Avg 58.169 ms)
main debug: Buffering 12%
direct3d debug: created video sub-window
direct3d debug: Vout EventThread running
direct3d debug: disabling screen saver
main debug: Buffering 12%
main debug: Buffering 16%
main debug: Buffering 16%
main debug: Buffering 19%
main debug: Buffering 20%
main debug: VoutDisplayEvent 'resize' 737x614 window
direct3d warning: trying surface pixel format: YV12
direct3d debug: selected surface pixel format is YV12
direct3d debug: Using D3DTEXF_LINEAR for minification
direct3d debug: Using D3DTEXF_LINEAR for magnification
direct3d debug: Direct3D scene created successfully
direct3d debug: Direct3D device adapter successfully initialized
main debug: using vout display module "direct3d"
main debug: TIMER module_need() : 165.884 ms - Total 165.884 ms / 1 intvls (Avg 165.884 ms)
main debug: original format sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 721:720, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 2 candidates
freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 1.153 ms - Total 1.153 ms / 1 intvls (Avg 1.153 ms)
avcodec debug: using direct rendering
main debug: VoutDisplayEvent 'resize' 721x576 window
main debug: End of video preroll
main debug: Buffering 22%
main debug: Received first picture
main debug: Buffering 24%
direct3d debug: Created 523x38 texture for OSD
main debug: Buffering 25%
main debug: Post-processing available
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 28%
main debug: Buffering 32%
main debug: Buffering 32%
main debug: Buffering 35%
main debug: Buffering 36%
main debug: Buffering 38%
main debug: Buffering 40%
main debug: Buffering 41%
main debug: Buffering 44%
main debug: Buffering 44%
main debug: Buffering 48%
main debug: Buffering 48%
main debug: Buffering 51%
main debug: Buffering 52%
main debug: Buffering 54%
main debug: Buffering 56%
main debug: Buffering 57%
main debug: Buffering 60%
main debug: Buffering 60%
main debug: Buffering 64%
main debug: Buffering 64%
main debug: Buffering 67%
main debug: Buffering 68%
main debug: Buffering 70%
main debug: Buffering 72%
main debug: Buffering 73%
main debug: Buffering 76%
main debug: Buffering 76%
main debug: Buffering 80%
main debug: Buffering 80%
main debug: Buffering 83%
main debug: Buffering 84%
main debug: Buffering 86%
main debug: Buffering 88%
main debug: Buffering 89%
main debug: Buffering 92%
main debug: Buffering 92%
main debug: Buffering 96%
main debug: Buffering 96%
main debug: Buffering 99%
main debug: Stream buffering done (1001 ms in 1000 ms)
main debug: Decoder buffering done in 0 ms
main warning: PTS is out of range (3442), dropping buffer
main warning: PTS is out of range (-27805), dropping buffer
main warning: buffer way too late (254000), dropping buffer
main warning: buffer way too late (222000), dropping buffer
main warning: buffer way too late (190000), dropping buffer
main warning: buffer too late  (158000), up-sampling
main warning: picture is too late to be displayed (missing 60 ms)
main warning: picture is too late to be displayed (missing 67 ms)
main warning: picture is too late to be displayed (missing 29 ms)
main debug: picture might be displayed late (missing 18 ms)
main debug: audio output is too slow (93909 us):  trashing 50000 us
main debug: audio output is too slow (43909 us):  trashing 50000 us
main warning: audio output out of sync, adjusting dates (103900 us)
main warning: not synchronized (103901 us), resampling
main warning: buffer way too late (272237), dropping buffer
main warning: buffer way too late (240237), dropping buffer
main warning: buffer way too late (208237), dropping buffer
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP()
main error: ES_OUT_RESET_PCR called
main warning: received buffer in the future
live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP()
main error: ES_OUT_RESET_PCR called
main debug: Buffering 0%
main debug: End of audio preroll
main debug: Buffering 2%
main debug: Buffering 3%
main debug: Buffering 6%
main debug: Buffering 6%
main debug: Buffering 9%
main debug: Buffering 10%
main debug: Buffering 12%
main debug: Buffering 14%
main debug: Buffering 16%
main debug: Buffering 18%
main debug: Buffering 19%
main debug: Buffering 22%
main debug: Buffering 22%
main debug: Buffering 25%
main debug: Buffering 26%
main debug: Buffering 28%
main debug: Buffering 30%
main debug: Buffering 32%
main debug: Buffering 34%
main debug: Buffering 35%
main debug: Buffering 38%
main debug: Buffering 38%
main debug: Buffering 41%
main debug: Buffering 42%
main debug: Buffering 44%
main debug: Buffering 46%
main debug: Buffering 48%
main debug: Buffering 50%
main debug: Buffering 51%
main debug: Buffering 54%
main debug: Buffering 54%
main debug: Buffering 58%
main debug: Buffering 58%
main debug: Buffering 60%
main debug: Buffering 62%
main debug: Buffering 64%
main debug: Buffering 66%
main debug: Buffering 67%
main debug: Buffering 70%
main debug: Buffering 70%
main debug: Buffering 73%
main debug: Buffering 74%
main debug: Buffering 76%
main debug: Buffering 78%
main debug: Buffering 80%
main debug: Buffering 82%
main debug: Buffering 83%
main debug: Buffering 86%
main debug: Buffering 86%
main debug: Buffering 89%
main debug: Buffering 90%
main debug: Buffering 92%
main debug: Buffering 94%
main debug: Buffering 96%
main debug: Buffering 98%
main debug: Buffering 99%
main debug: Stream buffering done (1023 ms in 1015 ms)
main debug: Decoder buffering done in 0 ms
main warning: PTS is out of range (-9959), dropping buffer
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
main debug: End of video preroll
main debug: auto hiding mouse cursor
main warning: picture is too late to be displayed (missing 27 ms)
main debug: auto hiding mouse cursor
live555 debug: StreamClose
main debug: EOF reached
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: waiting decoder fifos to empty
main debug: finished input
main debug: removing module "faad"
main debug: killing decoder fourcc `mp4a', 0 PES in FIFO
main debug: removing module "scaletempo"
main debug: removing module "samplerate"
main debug: removing module "aout_directx"
aout_directx debug: closing audio device
aout_directx debug: DirectSoundThread exiting
main debug: removing module "audio_format"
main debug: removing module "float32_mixer"
main debug: releasing audio output
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 0 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "packetizer_h264"
main debug: removing module "live555"
main debug: Program doesn't contain anymore ES
main debug: dead input
main debug: changing item without a request (current 0/1)
main debug: nothing to play
main debug: destroying useless vout
qt4 debug: IM: Deleting the input
main debug: removing module "direct3d"
direct3d debug: Direct3D scene released successfully
main debug: TIMER input launching for 'rtsp://192.168.150.241:554/Birds' : 394.650 ms - Total 394.650 ms / 1 intvls (Avg 394.650 ms)
direct3d debug: DirectXEventThread terminating
direct3d debug: DirectXCloseWindow
direct3d debug: WinProc WM_DESTROY
qt4 debug: releasing video...
qt4 debug: Video is not needed anymore
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"

Posted: **23 May 2012 16:23**

increase caching I think.

Posted: **23 May 2012 21:17**

Modern servers should send the first RTCP-SR immediately. This is recommended for unicast streaming.

There is no way to avoid the rebuffering on the first SR given how RTP works (unless maybe there is only one media line in the session).

The VideoLAN Forums

network stream rebuffering on the first RTCP SR

network stream rebuffering on the first RTCP SR

Re: network stream rebuffering on the first RTCP SR

Re: network stream rebuffering on the first RTCP SR