network stream rebuffering on the first RTCP SR

For questions and discussion that is NOT (I repeat NOT) specific to a certain Operating System.
mdw
New Cone
New Cone
Posts: 1
Joined: 23 May 2012 15:20

network stream rebuffering on the first RTCP SR

Postby mdw » 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"

Jean-Baptiste Kempf
Site Administrator
Site Administrator
Posts: 37523
Joined: 22 Jul 2005 15:29
VLC version: 4.0.0-git
Operating System: Linux, Windows, Mac
Location: Cone, France
Contact:

Re: network stream rebuffering on the first RTCP SR

Postby Jean-Baptiste Kempf » 23 May 2012 16:23

increase caching I think.
Jean-Baptiste Kempf
http://www.jbkempf.com/ - http://www.jbkempf.com/blog/category/Videolan
VLC media player developer, VideoLAN President and Sites administrator
If you want an answer to your question, just be specific and precise. Don't use Private Messages.

Rémi Denis-Courmont
Developer
Developer
Posts: 15323
Joined: 07 Jun 2004 16:01
VLC version: master
Operating System: Linux
Contact:

Re: network stream rebuffering on the first RTCP SR

Postby Rémi Denis-Courmont » 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).
Rémi Denis-Courmont
https://www.remlab.net/
Private messages soliciting support will be systematically discarded


Return to “General VLC media player Troubleshooting”

Who is online

Users browsing this forum: No registered users and 79 guests