Will only play RTSP at certain times of day (yes, very odd!)

[johnu]

We have an IP camera which we're doing development work on, it streams live H.264 over RTSP using the same streaming engine as VLC (Live555/WIS-Streamer, latest version 2015.12.22). We're using VLC 2.2.1 Terry Pratchett (Weatherwax).

We have found a REALLY perplexing problem though, and I'm hoping someone here may have seen this before and know what's going on.

Basically, if the time (wall clock time) in the camera is set to certain times, VLC will not play the stream - it will play the first few seconds, then the screen goes grey and eventually it times out. Other times, it will play fine, faultlessly.

I'm still trying to work out what the relationship may be between the system time of the PC VLC's running on and the system time of the camera - it's not down to time difference as I can set the camera time to exactly the same as the PC time and at different times of the day it will work or fail, likewise I can set the two systems to very different times and some will play and some won't.

We can see nothing different in the camera's RTSP stream, no errors, jumps, etc. and other tools such as mPlayer will play the stream perfectly. If I stream the video to a file using openRTSP, VLC will then play it quite happily!

I realise that this at least as likely to be "our" problem as VLC's, as everyone has a different reading of the RFC's when it comes to video + streaming, I was mostly just hoping someone might've seen this sort of behaviour before. Any suggestions at all would be welcome!

I can post stream data and wireshark logs if needed but obviously that can get a bit cumbersome.

Here's VLC's message logs for streaming, this is a "working" stream:

Code: Select all

core debug: processing request item: rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264, node: Playlist, skip: 0 core debug: resyncing on rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 is at 0 core debug: starting playback of the new playlist item core debug: resyncing on rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 is at 0 core debug: creating new input thread core debug: Creating an input for 'rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' core debug: requesting art for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: using timeshift granularity of 50 MiB, in path 'C:\Users\John\AppData\Local\Temp' core debug: `rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' gives access `rtsp' demux `' path `192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' core debug: specified demux `any' core debug: creating demux: access='rtsp' demux='any' location='192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' file='\\192.168.2.21:8557\PSIA\Streaming\channels\2?videoCodecType=H.264' core debug: looking for meta fetcher module matching "any": 1 candidates core debug: looking for access_demux module matching "rtsp": 12 candidates live555 debug: version 2014.07.25 lua debug: Trying Lua scripts in C:\Users\John\AppData\Roaming\vlc\lua\meta\fetcher lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac core debug: no meta fetcher modules matched core debug: searching art for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: looking for art finder module matching "any": 2 candidates lua debug: Trying Lua scripts in C:\Users\John\AppData\Roaming\vlc\lua\meta\art lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac core debug: no art finder modules matched core debug: art not found for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 qt4 debug: IM: Setting an input live555 debug: RTP subsession 'video/H264' core debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: We have a timeout of 120 seconds live555 debug: spawned timeout thread live555 debug: play start: 0.000000 stop:0.000000 core debug: using access_demux module "live555" core debug: looking for decoder module matching "any": 43 candidates avcodec debug: CPU flags: 0x010053db avcodec debug: trying to use direct rendering avcodec debug: allowing 4 thread(s) for decoding avcodec warning: threaded frame decoding is not compatible with DXVA2, disabled avcodec debug: avcodec codec (H264 - MPEG-4 AVC (part 10)) started avcodec debug: using slice thread mode with 4 threads core debug: using decoder module "avcodec" core debug: looking for packetizer module matching "any": 23 candidates packetizer_h264 debug: found NAL_SPS (sps_id=0) packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0) core debug: using packetizer module "packetizer_h264" core debug: looking for meta reader module matching "any": 2 candidates lua debug: Trying Lua scripts in C:\Users\John\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 core debug: no meta reader modules matched core debug: `rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' successfully opened live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP() core error: ES_OUT_RESET_PCR called core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 12% avcodec debug: available hardware decoder output format 61 (dxva2_vld) avcodec debug: available software decoder output format 12 (yuvj420p) core debug: looking for hw decoder module matching "any": 1 candidates core debug: no hw decoder modules matched core debug: looking for text renderer module matching "any": 2 candidates freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf freetype debug: Using Courier New as mono-font from file C:\Windows\Fonts\cour.ttf freetype debug: using fontsize: 2 core debug: using text renderer module "freetype" core debug: looking for video filter2 module matching "any": 58 candidates swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) core debug: using video filter2 module "swscale" core debug: looking for video filter2 module matching "any": 58 candidates yuvp debug: YUVP to YUVA converter core debug: using video filter2 module "yuvp" core debug: Deinterlacing available core debug: deinterlace 0, mode blend, is_needed 0 core debug: Opening vout display wrapper core debug: looking for vout display module matching "any": 11 candidates direct3d debug: creating Vout EventThread direct3d debug: Win32VoutCreateWindow core debug: looking for vout window hwnd module matching "qt4,any": 3 candidates qt4 debug: requesting video window... core debug: Buffering 24% qt4 debug: Video was requested 0, 0 core debug: using vout window hwnd module "qt4" direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver direct3d debug: Direct3d Device: NVIDIA GeForce GTX 550 Ti 4318 4676 161 core debug: Buffering 36% core debug: Buffering 48% core debug: Buffering 60% core debug: Buffering 72% core debug: Buffering 84% core debug: Buffering 96% core debug: Stream buffering done (360 ms in 144 ms) core debug: VoutDisplayEvent 'resize' 1936x1118 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 core debug: using vout display module "direct3d" core debug: original format sz 1920x1090, of (0,0), vsz 1920x1080, 4cc J420, sar 1:1, msk r0x0 g0x0 b0x0 core debug: removing module "freetype" core debug: VoutDisplayEvent 'resize' 1920x959 window core debug: looking for text renderer module matching "any": 2 candidates freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf freetype debug: Using Courier New as mono-font from file C:\Windows\Fonts\cour.ttf freetype debug: using fontsize: 2 core debug: using text renderer module "freetype" avcodec warning: plane 0 not aligned avcodec warning: disabling direct rendering core debug: End of video preroll core debug: Received first picture freetype error: Breaking unbreakable line core debug: Decoder wait done in 161 ms core warning: picture is too late to be displayed (missing 43 ms) core debug: picture might be displayed late (missing 10 ms) core warning: picture is too late to be displayed (missing 307 ms) core warning: picture is too late to be displayed (missing 267 ms) core warning: picture is too late to be displayed (missing 227 ms) core warning: picture is too late to be displayed (missing 187 ms) core warning: picture is too late to be displayed (missing 147 ms) core warning: picture is too late to be displayed (missing 107 ms) core warning: picture is too late to be displayed (missing 67 ms) core warning: picture is too late to be displayed (missing 27 ms) core debug: picture might be displayed late (missing 7 ms) core debug: auto hiding mouse cursor core warning: clock gap, unexpected stream discontinuity core warning: feeding synchro with a new reference point trying to recover from clock gap core warning: early picture skipped core debug: picture might be displayed late (missing 5 ms) core debug: incoming request - stopping current input core debug: control: stopping input core debug: incoming request - stopping current input core debug: finished input core debug: removing module "avcodec" avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped core debug: killing decoder fourcc `h264', 0 PES in FIFO core debug: saving a free vout core debug: reusing provided vout qt4 debug: Qt: Fullscreen state changed core debug: removing module "packetizer_h264" core debug: removing module "live555" core debug: Program doesn't contain anymore ES core debug: incoming request - stopping current input core debug: dead input core debug: destroying useless vout core debug: removing module "direct3d" direct3d debug: Direct3D scene released successfully qt4 debug: IM: Deleting the input direct3d debug: Win32 Vout EventThread terminating direct3d debug: Win32VoutCloseWindow direct3d debug: WinProc WM_DESTROY qt4 debug: releasing video... qt4 debug: Video is not needed anymore core debug: removing module "freetype" core debug: removing module "yuvp" core debug: removing module "swscale"

And this is a non-working stream:

Code: Select all

core debug: adding item `rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' ( rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 ) core debug: meta ok for (null), need to fetch art core debug: processing request item: rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264, node: null, skip: 0 core debug: rebuilding array of current - root Playlist core debug: rebuild done - 1 items, index 0 core debug: starting playback of the new playlist item core debug: resyncing on rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 is at 0 core debug: creating new input thread core debug: Creating an input for 'rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' core debug: looking for meta fetcher module matching "any": 1 candidates core debug: requesting art for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: using timeshift granularity of 50 MiB, in path 'C:\Users\John\AppData\Local\Temp' core debug: `rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' gives access `rtsp' demux `' path `192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' core debug: specified demux `any' lua debug: Trying Lua scripts in C:\Users\John\AppData\Roaming\vlc\lua\meta\fetcher core debug: creating demux: access='rtsp' demux='any' location='192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' file='\\192.168.2.21:8557\PSIA\Streaming\channels\2?videoCodecType=H.264' core debug: looking for access_demux module matching "rtsp": 12 candidates lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac live555 debug: version 2014.07.25 core debug: looking for meta fetcher module matching "any": 1 candidates lua debug: Trying Lua scripts in C:\Users\John\AppData\Roaming\vlc\lua\meta\fetcher lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\fetcher core debug: no meta fetcher modules matched core debug: searching art for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: looking for art finder module matching "any": 2 candidates lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac lua debug: Trying Lua scripts in C:\Users\John\AppData\Roaming\vlc\lua\meta\art lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac core debug: no meta fetcher modules matched core debug: searching art for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 core debug: looking for art finder module matching "any": 2 candidates lua debug: Trying Lua scripts in C:\Users\John\AppData\Roaming\vlc\lua\meta\art lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\meta\art lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\00_musicbrainz.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\01_googleimage.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.luac lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac core debug: no art finder modules matched core debug: art not found for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 lua debug: skipping script (unmatched scope) C:\Program Files\VideoLAN\VLC\lua\meta\art\03_lastfm.luac core debug: no art finder modules matched core debug: art not found for rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264 qt4 debug: IM: Setting an input live555 debug: RTP subsession 'video/H264' core debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: We have a timeout of 120 seconds live555 debug: spawned timeout thread live555 debug: play start: 0.000000 stop:0.000000 core debug: using access_demux module "live555" core debug: looking for decoder module matching "any": 43 candidates avcodec debug: CPU flags: 0x010053db avcodec debug: trying to use direct rendering avcodec debug: allowing 4 thread(s) for decoding avcodec warning: threaded frame decoding is not compatible with DXVA2, disabled avcodec debug: avcodec codec (H264 - MPEG-4 AVC (part 10)) started avcodec debug: using slice thread mode with 4 threads core debug: using decoder module "avcodec" core debug: looking for packetizer module matching "any": 23 candidates packetizer_h264 debug: found NAL_SPS (sps_id=0) packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0) core debug: using packetizer module "packetizer_h264" core debug: looking for meta reader module matching "any": 2 candidates lua debug: Trying Lua scripts in C:\Users\John\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 core debug: no meta reader modules matched core debug: `rtsp://192.168.2.21:8557/PSIA/Streaming/channels/2?videoCodecType=H.264' successfully opened live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP() core error: ES_OUT_RESET_PCR called core debug: Buffering 0% core debug: Buffering 12% core debug: Buffering 24% core debug: Buffering 36% core debug: Buffering 48% core debug: Buffering 60% core debug: Buffering 72% core debug: Buffering 84% core debug: Buffering 96% core debug: Stream buffering done (360 ms in 360 ms) core debug: Decoder wait done in 0 ms avcodec debug: available hardware decoder output format 61 (dxva2_vld) avcodec debug: available software decoder output format 12 (yuvj420p) core debug: looking for hw decoder module matching "any": 1 candidates core debug: no hw decoder modules matched core debug: looking for text renderer module matching "any": 2 candidates freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf freetype debug: Using Courier New as mono-font from file C:\Windows\Fonts\cour.ttf freetype debug: using fontsize: 2 core debug: using text renderer module "freetype" core debug: looking for video filter2 module matching "any": 58 candidates swscale debug: 32x32 (32x32) chroma: YUVA -> 16x16 (16x16) chroma: RGBA with scaling using Bicubic (good quality) core debug: using video filter2 module "swscale" core debug: looking for video filter2 module matching "any": 58 candidates yuvp debug: YUVP to YUVA converter core debug: using video filter2 module "yuvp" core debug: Deinterlacing available core debug: deinterlace 0, mode blend, is_needed 0 core debug: Opening vout display wrapper core debug: looking for vout display module matching "any": 11 candidates direct3d debug: creating Vout EventThread direct3d debug: Win32VoutCreateWindow core debug: looking for vout window hwnd module matching "qt4,any": 3 candidates qt4 debug: requesting video window... qt4 debug: Video was requested 0, 0 core debug: using vout window hwnd module "qt4" direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver direct3d debug: Direct3d Device: NVIDIA GeForce GTX 550 Ti 4318 4676 161 core debug: VoutDisplayEvent 'resize' 1936x1118 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 core debug: using vout display module "direct3d" core debug: original format sz 1920x1090, of (0,0), vsz 1920x1080, 4cc J420, sar 1:1, msk r0x0 g0x0 b0x0 core debug: removing module "freetype" core debug: VoutDisplayEvent 'resize' 1920x959 window core debug: looking for text renderer module matching "any": 2 candidates freetype debug: Using Arial as font from file C:\Windows\Fonts\arial.ttf freetype debug: Using Courier New as mono-font from file C:\Windows\Fonts\cour.ttf freetype debug: using fontsize: 2 core debug: using text renderer module "freetype" avcodec warning: plane 0 not aligned avcodec warning: disabling direct rendering avcodec warning: cannot decode one frame (477979 bytes) core debug: End of video preroll freetype error: Breaking unbreakable line core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core debug: auto hiding mouse cursor core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core debug: auto hiding mouse cursor core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core warning: early picture skipped core error: ES_OUT_SET_(GROUP_)PCR is called too late (pts_delay increased to 333 ms) core error: ES_OUT_RESET_PCR called core warning: early picture skipped core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% avcodec debug: available hardware decoder output format 61 (dxva2_vld) avcodec debug: available software decoder output format 12 (yuvj420p) core debug: looking for hw decoder module matching "any": 1 candidates core debug: no hw decoder modules matched core debug: Buffering 0% core debug: End of video preroll core debug: Received first picture core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: auto hiding mouse cursor core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: auto hiding mouse cursor core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: auto hiding mouse cursor core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: auto hiding mouse cursor core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: Buffering 0% core debug: incoming request - stopping current input core debug: control: stopping input core debug: incoming request - stopping current input core warning: early picture skipped core debug: finished input core debug: removing module "avcodec" avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped core debug: killing decoder fourcc `h264', 169 PES in FIFO core debug: auto hiding mouse cursor core debug: saving a free vout core debug: reusing provided vout qt4 debug: Qt: Fullscreen state changed core debug: removing module "packetizer_h264" core debug: removing module "live555" core debug: Program doesn't contain anymore ES core debug: incoming request - stopping current input core debug: dead input core debug: destroying useless vout core debug: removing module "direct3d" direct3d debug: Direct3D scene released successfully qt4 debug: IM: Deleting the input direct3d debug: Win32 Vout EventThread terminating direct3d debug: Win32VoutCloseWindow direct3d debug: WinProc WM_DESTROY qt4 debug: releasing video... qt4 debug: Video is not needed anymore core debug: removing module "freetype" core debug: removing module "yuvp" core debug: removing module "swscale"

[johnu]

Even more curiouser it works flawlessly if we set the camera to multicast.

It seems to be related to the time in the camera (so camera wall-clock time Vs RTSP-timestamp) rather than the wall-clock time in the PC Vs Camera.

[Rémi Denis-Courmont]

Maybe camera is sending incorrect RTCP SR compounds?

[Ludrax]

even in that case

We can see nothing different in the camera's RTSP stream, no errors, jumps, etc. and other tools such as mPlayer will play the stream perfectly. If I stream the video to a file using openRTSP, VLC will then play it quite happily!

We're using VLC 2.2.1 Terry Pratchett (Weatherwax).

[johnu]

I can post Wireshark captures of both working + not working, or just a dump of the SR's, what would be the most useful?

[johnu]

Some test results created as follows:

Wireshark set running...
Camera time set to ~9am, stream started, plays fine.

Here's the SR and RR's sent during that working stream:
(summary from Wireshark)

Code: Select all

Frame 16: 98 bytes on wire (784 bits), 98 bytes captured (784 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:12:38.687971000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714758.687971000 seconds [Time delta from previous captured frame: 0.003659000 seconds] [Time delta from previous displayed frame: 0.003659000 seconds] [Time since reference or first frame: 0.026448000 seconds] Frame Number: 16 Frame Length: 98 bytes (784 bits) Capture Length: 98 bytes (784 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa), Dst: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Destination: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Source: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.21, Dst: 192.168.2.108 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 84 Identification: 0x0000 (0) Flags: 0x02 (Don't Fragment) Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xb4c7 [validation disabled] Source: 192.168.2.21 Destination: 192.168.2.108 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 6971 (6971), Dst Port: 56391 (56391) Source Port: 6971 (6971) Destination Port: 56391 (56391) Length: 64 Checksum: 0xa8b5 [validation disabled] [Stream index: 1] Real-time Transport Control Protocol (Sender Report) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0000 = Reception report count: 0 Packet type: Sender Report (200) Length: 6 (28 bytes) Sender SSRC: 0x2ca41a40 (748952128) Timestamp, MSW: 3664689151 (0xda6eb7ff) Timestamp, LSW: 1349427185 (0x506e9ff1) [MSW and LSW as NTP timestamp: Feb 17, 2016 09:12:31.314188000 UTC] RTP timestamp: 4284447724 Sender's packet count: 0 Sender's octet count: 0 Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 6 (28 bytes) Chunk 1, SSRC/CSRC 0x2CA41A40 [RTCP frame length check: OK - 56 bytes]

Code: Select all

Frame 1306: 98 bytes on wire (784 bits), 98 bytes captured (784 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:12:41.334917000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714761.334917000 seconds [Time delta from previous captured frame: 0.028088000 seconds] [Time delta from previous displayed frame: 0.028088000 seconds] [Time since reference or first frame: 2.673394000 seconds] Frame Number: 1306 Frame Length: 98 bytes (784 bits) Capture Length: 98 bytes (784 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa), Dst: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Destination: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Source: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.21, Dst: 192.168.2.108 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 84 Identification: 0x0000 (0) Flags: 0x02 (Don't Fragment) Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xb4c7 [validation disabled] Source: 192.168.2.21 Destination: 192.168.2.108 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 6971 (6971), Dst Port: 56391 (56391) Source Port: 6971 (6971) Destination Port: 56391 (56391) Length: 64 Checksum: 0xec3a [validation disabled] [Stream index: 1] Real-time Transport Control Protocol (Sender Report) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0000 = Reception report count: 0 Packet type: Sender Report (200) Length: 6 (28 bytes) Sender SSRC: 0x2ca41a40 (748952128) Timestamp, MSW: 3664689153 (0xda6eb801) Timestamp, LSW: 4130358379 (0xf630446b) [MSW and LSW as NTP timestamp: Feb 17, 2016 09:12:33.961673000 UTC] RTP timestamp: 3431892490 Sender's packet count: 1261 Sender's octet count: 1742312 Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 6 (28 bytes) Chunk 1, SSRC/CSRC 0x2CA41A40 [RTCP frame length check: OK - 56 bytes]

Code: Select all

Frame 1410: 94 bytes on wire (752 bits), 94 bytes captured (752 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:12:41.656355000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714761.656355000 seconds [Time delta from previous captured frame: 0.000144000 seconds] [Time delta from previous displayed frame: 0.000144000 seconds] [Time since reference or first frame: 2.994832000 seconds] Frame Number: 1410 Frame Length: 94 bytes (752 bits) Capture Length: 94 bytes (752 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0), Dst: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Destination: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Source: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.108, Dst: 192.168.2.21 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 80 Identification: 0x1ac9 (6857) Flags: 0x00 Fragment offset: 0 Time to live: 128 Protocol: UDP (17) Header checksum: 0x9a02 [validation disabled] Source: 192.168.2.108 Destination: 192.168.2.21 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 56391 (56391), Dst Port: 6971 (6971) Source Port: 56391 (56391) Destination Port: 6971 (6971) Length: 60 Checksum: 0xbf2a [validation disabled] [Stream index: 1] Real-time Transport Control Protocol (Receiver Report) Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 4 (20 bytes) Chunk 1, SSRC/CSRC 0x31782C6F [RTCP frame length check: OK - 52 bytes]

Code: Select all

Frame 2231: 94 bytes on wire (752 bits), 94 bytes captured (752 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:12:43.866588000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714763.866588000 seconds [Time delta from previous captured frame: 0.000085000 seconds] [Time delta from previous displayed frame: 0.000085000 seconds] [Time since reference or first frame: 5.205065000 seconds] Frame Number: 2231 Frame Length: 94 bytes (752 bits) Capture Length: 94 bytes (752 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0), Dst: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Destination: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Source: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.108, Dst: 192.168.2.21 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 80 Identification: 0x1aca (6858) Flags: 0x00 Fragment offset: 0 Time to live: 128 Protocol: UDP (17) Header checksum: 0x9a01 [validation disabled] Source: 192.168.2.108 Destination: 192.168.2.21 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 56391 (56391), Dst Port: 6971 (6971) Source Port: 56391 (56391) Destination Port: 6971 (6971) Length: 60 Checksum: 0x81f4 [validation disabled] [Stream index: 1] Real-time Transport Control Protocol (Receiver Report) Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 4 (20 bytes) Chunk 1, SSRC/CSRC 0x31782C6F [RTCP frame length check: OK - 52 bytes]

Code: Select all

Frame 2380: 98 bytes on wire (784 bits), 98 bytes captured (784 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:12:44.361985000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714764.361985000 seconds [Time delta from previous captured frame: 0.002167000 seconds] [Time delta from previous displayed frame: 0.034598000 seconds] [Time since reference or first frame: 5.700462000 seconds] Frame Number: 2380 Frame Length: 98 bytes (784 bits) Capture Length: 98 bytes (784 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa), Dst: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Destination: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Source: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.21, Dst: 192.168.2.108 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 84 Identification: 0x0000 (0) Flags: 0x02 (Don't Fragment) Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xb4c7 [validation disabled] Source: 192.168.2.21 Destination: 192.168.2.108 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 6971 (6971), Dst Port: 56391 (56391) Source Port: 6971 (6971) Destination Port: 56391 (56391) Length: 64 Checksum: 0xc53c [validation disabled] [Stream index: 1] Real-time Transport Control Protocol (Sender Report) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0000 = Reception report count: 0 Packet type: Sender Report (200) Length: 6 (28 bytes) Sender SSRC: 0x2ca41a40 (748952128) Timestamp, MSW: 3664689156 (0xda6eb804) Timestamp, LSW: 4243371854 (0xfcecb74e) [MSW and LSW as NTP timestamp: Feb 17, 2016 09:12:36.987987000 UTC] RTP timestamp: 3432164858 Sender's packet count: 2329 Sender's octet count: 3217165 Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 6 (28 bytes) Chunk 1, SSRC/CSRC 0x2CA41A40 [RTCP frame length check: OK - 56 bytes]

Code: Select all

Frame 3778: 82 bytes on wire (656 bits), 82 bytes captured (656 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:12:48.273626000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714768.273626000 seconds [Time delta from previous captured frame: 0.000046000 seconds] [Time delta from previous displayed frame: 0.000046000 seconds] [Time since reference or first frame: 9.612103000 seconds] Frame Number: 3778 Frame Length: 82 bytes (656 bits) Capture Length: 82 bytes (656 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0), Dst: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Destination: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Source: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.108, Dst: 192.168.2.21 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 68 Identification: 0x1acc (6860) Flags: 0x00 Fragment offset: 0 Time to live: 128 Protocol: UDP (17) Header checksum: 0x9a0b [validation disabled] Source: 192.168.2.108 Destination: 192.168.2.21 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 56391 (56391), Dst Port: 6971 (6971) Source Port: 56391 (56391) Destination Port: 6971 (6971) Length: 48 Checksum: 0x37d1 [validation disabled] [Stream index: 1] Real-time Transport Control Protocol (Receiver Report) Real-time Transport Control Protocol (Goodbye) [RTCP frame length check: OK - 40 bytes]

Here's the SR and RR's sent during that non-working stream:
(summary from Wireshark)

Code: Select all

Frame 5031: 98 bytes on wire (784 bits), 98 bytes captured (784 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:13:23.083887000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714803.083887000 seconds [Time delta from previous captured frame: 0.005728000 seconds] [Time delta from previous displayed frame: 0.005728000 seconds] [Time since reference or first frame: 44.422364000 seconds] Frame Number: 5031 Frame Length: 98 bytes (784 bits) Capture Length: 98 bytes (784 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa), Dst: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Destination: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Source: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.21, Dst: 192.168.2.108 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 84 Identification: 0x0000 (0) Flags: 0x02 (Don't Fragment) Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xb4c7 [validation disabled] Source: 192.168.2.21 Destination: 192.168.2.108 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 6971 (6971), Dst Port: 60757 (60757) Source Port: 6971 (6971) Destination Port: 60757 (60757) Length: 64 Checksum: 0x8b8f [validation disabled] [Stream index: 28] Real-time Transport Control Protocol (Sender Report) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0000 = Reception report count: 0 Packet type: Sender Report (200) Length: 6 (28 bytes) Sender SSRC: 0xc9bc7745 (3384571717) Timestamp, MSW: 3664703606 (0xda6ef076) Timestamp, LSW: 231971184 (0x0dd39970) [MSW and LSW as NTP timestamp: Feb 17, 2016 13:13:26.054010000 UTC] RTP timestamp: 650149133 Sender's packet count: 0 Sender's octet count: 0 Real-time Transport Control Protocol (Source description) [RTCP frame length check: OK - 56 bytes]

Code: Select all

Frame 6073: 94 bytes on wire (752 bits), 94 bytes captured (752 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:13:25.716240000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714805.716240000 seconds [Time delta from previous captured frame: 0.018681000 seconds] [Time delta from previous displayed frame: 0.018681000 seconds] [Time since reference or first frame: 47.054717000 seconds] Frame Number: 6073 Frame Length: 94 bytes (752 bits) Capture Length: 94 bytes (752 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0), Dst: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Destination: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Source: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.108, Dst: 192.168.2.21 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 80 Identification: 0x1c9b (7323) Flags: 0x00 Fragment offset: 0 Time to live: 128 Protocol: UDP (17) Header checksum: 0x9830 [validation disabled] Source: 192.168.2.108 Destination: 192.168.2.21 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 60757 (60757), Dst Port: 6971 (6971) Source Port: 60757 (60757) Destination Port: 6971 (6971) Length: 60 Checksum: 0xf27c [validation disabled] [Stream index: 28] Real-time Transport Control Protocol (Receiver Report) Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 4 (20 bytes) Chunk 1, SSRC/CSRC 0xC0DD72C4 [RTCP frame length check: OK - 52 bytes]

Code: Select all

Frame 6241: 98 bytes on wire (784 bits), 98 bytes captured (784 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:13:26.132439000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714806.132439000 seconds [Time delta from previous captured frame: 0.036781000 seconds] [Time delta from previous displayed frame: 0.036781000 seconds] [Time since reference or first frame: 47.470916000 seconds] Frame Number: 6241 Frame Length: 98 bytes (784 bits) Capture Length: 98 bytes (784 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa), Dst: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Destination: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Source: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.21, Dst: 192.168.2.108 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 84 Identification: 0x0000 (0) Flags: 0x02 (Don't Fragment) Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xb4c7 [validation disabled] Source: 192.168.2.21 Destination: 192.168.2.108 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 6971 (6971), Dst Port: 60757 (60757) Source Port: 6971 (6971) Destination Port: 60757 (60757) Length: 64 Checksum: 0x2a96 [validation disabled] [Stream index: 28] Real-time Transport Control Protocol (Sender Report) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0000 = Reception report count: 0 Packet type: Sender Report (200) Length: 6 (28 bytes) Sender SSRC: 0xc9bc7745 (3384571717) Timestamp, MSW: 3664703609 (0xda6ef079) Timestamp, LSW: 443940705 (0x1a75ff61) [MSW and LSW as NTP timestamp: Feb 17, 2016 13:13:29.103363000 UTC] RTP timestamp: 1094506087 Sender's packet count: 1202 Sender's octet count: 1668545 Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 6 (28 bytes) Chunk 1, SSRC/CSRC 0xC9BC7745 [RTCP frame length check: OK - 56 bytes]

Code: Select all

Frame 7598: 94 bytes on wire (752 bits), 94 bytes captured (752 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:13:29.971728000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714809.971728000 seconds [Time delta from previous captured frame: 0.022566000 seconds] [Time delta from previous displayed frame: 0.022566000 seconds] [Time since reference or first frame: 51.310205000 seconds] Frame Number: 7598 Frame Length: 94 bytes (752 bits) Capture Length: 94 bytes (752 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0), Dst: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Destination: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Source: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.108, Dst: 192.168.2.21 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 80 Identification: 0x1c9f (7327) Flags: 0x00 Fragment offset: 0 Time to live: 128 Protocol: UDP (17) Header checksum: 0x982c [validation disabled] Source: 192.168.2.108 Destination: 192.168.2.21 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 60757 (60757), Dst Port: 6971 (6971) Source Port: 60757 (60757) Destination Port: 6971 (6971) Length: 60 Checksum: 0xa6e9 [validation disabled] [Stream index: 28] Real-time Transport Control Protocol (Receiver Report) Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 4 (20 bytes) Chunk 1, SSRC/CSRC 0xC0DD72C4 [RTCP frame length check: OK - 52 bytes]

Code: Select all

Frame 7720: 98 bytes on wire (784 bits), 98 bytes captured (784 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:13:30.351120000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714810.351120000 seconds [Time delta from previous captured frame: 0.019416000 seconds] [Time delta from previous displayed frame: 0.019416000 seconds] [Time since reference or first frame: 51.689597000 seconds] Frame Number: 7720 Frame Length: 98 bytes (784 bits) Capture Length: 98 bytes (784 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa), Dst: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Destination: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Source: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.21, Dst: 192.168.2.108 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 84 Identification: 0x0000 (0) Flags: 0x02 (Don't Fragment) Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xb4c7 [validation disabled] Source: 192.168.2.21 Destination: 192.168.2.108 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 6971 (6971), Dst Port: 60757 (60757) Source Port: 6971 (6971) Destination Port: 60757 (60757) Length: 64 Checksum: 0x69e7 [validation disabled] [Stream index: 28] Real-time Transport Control Protocol (Sender Report) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0000 = Reception report count: 0 Packet type: Sender Report (200) Length: 6 (28 bytes) Sender SSRC: 0xc9bc7745 (3384571717) Timestamp, MSW: 3664703613 (0xda6ef07d) Timestamp, LSW: 1383567880 (0x52779208) [MSW and LSW as NTP timestamp: Feb 17, 2016 13:13:33.322137000 UTC] RTP timestamp: 1094885776 Sender's packet count: 2674 Sender's octet count: 3709621 Real-time Transport Control Protocol (Source description) 10.. .... = Version: RFC 1889 Version (2) ..0. .... = Padding: False ...0 0001 = Source count: 1 Packet type: Source description (202) Length: 6 (28 bytes) Chunk 1, SSRC/CSRC 0xC9BC7745 [RTCP frame length check: OK - 56 bytes]

Code: Select all

Frame 8484: 82 bytes on wire (656 bits), 82 bytes captured (656 bits) on interface 0 Interface id: 0 (\Device\NPF_{9F3803ED-AE5D-4668-BD32-FEED605EB08D}) Encapsulation type: Ethernet (1) Arrival Time: Feb 17, 2016 13:13:32.540674000 GMT Standard Time [Time shift for this packet: 0.000000000 seconds] Epoch Time: 1455714812.540674000 seconds [Time delta from previous captured frame: 0.000056000 seconds] [Time delta from previous displayed frame: 0.000056000 seconds] [Time since reference or first frame: 53.879151000 seconds] Frame Number: 8484 Frame Length: 82 bytes (656 bits) Capture Length: 82 bytes (656 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ethertype:ip:udp:rtcp] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0), Dst: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Destination: IeeeRegi_0f:ff:fa (bc:34:00:0f:ff:fa) Source: Giga-Byt_37:02:c0 (50:e5:49:37:02:c0) Type: IPv4 (0x0800) Internet Protocol Version 4, Src: 192.168.2.108, Dst: 192.168.2.21 0100 .... = Version: 4 .... 0101 = Header Length: 20 bytes Differentiated Services Field: 0x00 (DSCP: CS0, ECN: Not-ECT) Total Length: 68 Identification: 0x1ca1 (7329) Flags: 0x00 Fragment offset: 0 Time to live: 128 Protocol: UDP (17) Header checksum: 0x9836 [validation disabled] Source: 192.168.2.108 Destination: 192.168.2.21 [Source GeoIP: Unknown] [Destination GeoIP: Unknown] User Datagram Protocol, Src Port: 60757 (60757), Dst Port: 6971 (6971) Source Port: 60757 (60757) Destination Port: 6971 (6971) Length: 48 Checksum: 0x3612 [validation disabled] [Stream index: 28] Real-time Transport Control Protocol (Receiver Report) Real-time Transport Control Protocol (Goodbye) [RTCP frame length check: OK - 40 bytes]

[johnu]

Anything else I can do to move this along? We really need to work out what's going on and what we can do about it.

[sampsa]

Hi,

i think my recent post is a bit related:

https://forum.videolan.org/viewtopic.php?f=2&t=130841

I have had these problems ("buffering 0%" or "video 5 secs too late") for a long time in a number of situations.

I think it is because of corrupted RTCP timestamps (you did not provide any resumen of our wireshark tests, btw.. dont feel like reading all that output). If you disable RTCP timestamps, then I think, you will force vlc (and its live555 component) to default to plain RTP timestamps and your problems might disapperar.

[johnu]

(you did not provide any resumen of our wireshark tests, btw.. dont feel like reading all that output)

Do you mean I didn't summarise the wireshark data? If you read back I did ask what form I should post data, which information I might filter out etc. to be most useful & readable but got no answer. If you want the data in ANY form I will gladly provide it, just ask!

I think it is because of corrupted RTCP timestamps. If you disable RTCP timestamps, then I think, you will force vlc (and its live555 component) to default to plain RTP timestamps and your problems might disapperar.

I'm pretty sure our RTCP timestamps are OK, as mentioned our streamer uses the latest Live555 streamer and we can't see any obvious errors in what we're sending. If you mean the problem is with VLC then breaking our stream to appease buggy software seems like a poor solution.

I've noticed that when VLC fails to play the stream, the time counter at the bottom left gets stuck a few seconds in, always something like 00:03 or 00:10, whereas when it runs OK it seems to get a different time after a few seconds, like 2:56:30 or 4:23:12, I'm not sure where that number is coming from (which timestamp information it uses to generate that)?

[Rémi Denis-Courmont]

I think the point is that debugging your problem sounds tedious, if at all possible without your stream. And then, it is unlikely that someone (else) will volunteer their time and make the effort to investigate it.

[johnu]

Rémi, are you saying I should post the raw stream data downloaded by VLC or something like that? As I keep saying, if there is ANYTHING I can provide that will help diagnose this I will happily provide it, but I don't want to spend hours capturing & uploading data that is of no use or "too tedious" for people to look at.

[Rémi Denis-Courmont]

Sorry, I don't have motivation and free time to solve your problem, and possibly not the expertise either.

[Ludrax]

if there is ANYTHING I can provide that will help diagnose this I will happily provide it, but I don't want to spend hours capturing & uploading data that is of no use or "too tedious" for people to look at.

this will save that time
Should you need timely and/or professional support, you will need to ​fix the problem yourself or to hire a ​consultant.

[johnu]

I appreciate that this is a free forum and that people have other things to do (so do I), but this is the VLC Support forum so I'm just trying to ask politely for a bit of support and avoid spamming a huge data-dump which some would consider anti-social and unhelpful. That's what I mean by not wanting to spend hours capturing useless data - I don't want to waste YOUR time reading it if it's of no use.

So far I haven't had an answer to what data I should post that would be useful to anyone who may be willing & able to help me, it seems like Remi may have an idea on that but is not willing to share it I'm sorry but I don't get it, why post that you won't help but don't tell me your idea on what I should have posted?

[Rémi Denis-Courmont]

If I were to debug, I would want a working link with which to reproduce the problem, or else a packet capture. But that's just me. You should first find somebody to help you before you ask what s/he needs.