RTSP VOD will not count playback position
Posted: 15 Sep 2013 13:48
I have a problem with playing VOD streams from my IPTV provider (streams are RTSP).
Video timer will not go up, it will stay at 00:00 with regular playback. It will only go up when seeking (for example if you seek to 15:00, timer will change to 15:00), but again stay at that time.
That would not be problematic, if it would not break relative seeking with keyboard (Ctrl + Left etc.). That means that for example if you start watching and watch until 15:00 and then want to seek 10 minutes ahead, VLC will seek to 10:00 instead of 25:00, because it would think you are still at 00:00.
I realize problem is probably on server side or at least compatibility between server and VLC, but I can't really affect that because server is not mine (it is from IPTV provider). Is there a way I could solve it client-side? Maybe by somehow tricking VLC into counting time manually on client side instead of trusting server?
I can't really give you any examples (RTSP streams only work inside network of my provider), but I can provide any other info. I pasted logs below, anything else should I include?
(Logs consist of opening stream and then seeking to random location in it).
Video timer will not go up, it will stay at 00:00 with regular playback. It will only go up when seeking (for example if you seek to 15:00, timer will change to 15:00), but again stay at that time.
That would not be problematic, if it would not break relative seeking with keyboard (Ctrl + Left etc.). That means that for example if you start watching and watch until 15:00 and then want to seek 10 minutes ahead, VLC will seek to 10:00 instead of 25:00, because it would think you are still at 00:00.
I realize problem is probably on server side or at least compatibility between server and VLC, but I can't really affect that because server is not mine (it is from IPTV provider). Is there a way I could solve it client-side? Maybe by somehow tricking VLC into counting time manually on client side instead of trusting server?
I can't really give you any examples (RTSP streams only work inside network of my provider), but I can provide any other info. I pasted logs below, anything else should I include?
Code: Select all
main debug: adding item `rtsp://10.253.7.115:554/cup_poptv_10506506.ts' ( rtsp://10.253.7.115:554/cup_poptv_10506506.ts )
qt4 debug: Adding a new MRL to recent ones: rtsp://10.253.7.115:554/cup_poptv_10506506.ts
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 2 items, index 0
main debug: processing request item: rtsp://10.253.7.115:554/cup_poptv_10506506.ts, node: null, skip: 0
main debug: resyncing on rtsp://10.253.7.115:554/cup_poptv_10506506.ts
main debug: rtsp://10.253.7.115:554/cup_poptv_10506506.ts is at 1
main debug: starting playback of the new playlist item
main debug: resyncing on rtsp://10.253.7.115:554/cup_poptv_10506506.ts
main debug: rtsp://10.253.7.115:554/cup_poptv_10506506.ts is at 1
main debug: creating new input thread
main debug: Creating an input for 'rtsp://10.253.7.115:554/cup_poptv_10506506.ts'
main debug: no fetch required for (null) (art currently (null))
main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\Matej\AppData\Local\Temp'
main debug: `rtsp://10.253.7.115:554/cup_poptv_10506506.ts' gives access `rtsp' demux `' path `10.253.7.115:554/cup_poptv_10506506.ts'
main debug: creating demux: access='rtsp' demux='' location='10.253.7.115:554/cup_poptv_10506506.ts' file='\\10.253.7.115:554\cup_poptv_10506506.ts'
main debug: looking for access_demux module: 1 candidate
live555 debug: version 2012.12.18
qt4 debug: IM: Setting an input
live555 debug: RTP subsession 'video/MP2T'
live555 debug: setup start: 0.000000 stop:899.000000
main debug: creating demux: access='' demux='ts' location='' file='(null)'
live555 debug: We have a timeout of 60 seconds
live555 debug: spawned timeout thread
live555 debug: play start: 0.000000 stop:899.000000
main debug: using access_demux module "live555"
main debug: TIMER module_need() : 94.479 ms - Total 94.479 ms / 1 intvls (Avg 94.479 ms)
main debug: looking for meta reader module: 2 candidates
lua debug: Trying Lua scripts in C:\Users\Matej\AppData\Roaming\vlc\lua\meta\reader
lua debug: Trying Lua scripts in C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader
lua debug: Trying Lua playlist script C:\Program Files (x86)\VideoLAN\VLC\lua\meta\reader\filename.luac
main debug: no meta reader module matching "any" could be loaded
main debug: TIMER module_need() : 0.720 ms - Total 0.720 ms / 1 intvls (Avg 0.720 ms)
main debug: `rtsp://10.253.7.115:554/cup_poptv_10506506.ts' successfully opened
main debug: looking for demux module: 1 candidate
ts debug: pid[257] unknown
ts debug: pid[258] unknown
ts debug: pid[770] unknown
ts debug: PATCallBack called
ts debug: new PAT ts_id=1 version=11 current_next=1
ts debug: * number=1 pid=33
ts debug: PMTCallBack called
ts debug: new PMT program number=1 version=1 pid_pcr=257
ts debug: * es pid=257 type=27 fcc=h264
main debug: selecting program id=1
main debug: looking for decoder module: 32 candidates
avcodec debug: libavcodec already initialized
avcodec debug: trying to use direct rendering
avcodec debug: allowing 4 thread(s) for decoding
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) started
avcodec debug: using frame thread mode with 4 threads
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 1.860 ms - Total 1.860 ms / 1 intvls (Avg 1.860 ms)
main debug: looking for packetizer module: 21 candidates
main debug: using packetizer module "packetizer_h264"
main debug: TIMER module_need() : 0.165 ms - Total 0.165 ms / 1 intvls (Avg 0.165 ms)
ts debug: * es pid=258 type=4 dr->i_tag=0xa
ts debug: found language: slv
ts debug: * es pid=258 type=4 fcc=mpga
main debug: looking for decoder module: 32 candidates
main debug: using decoder module "mpeg_audio"
main debug: TIMER module_need() : 0.349 ms - Total 0.349 ms / 1 intvls (Avg 0.349 ms)
ts debug: * es pid=770 type=6 dr->i_tag=0x56
ts debug: * ttxt type=Teletext lan=slv page=100
ts debug: * es pid=770 type=6 fcc=telx
main debug: using demux module "ts"
main debug: TIMER module_need() : 47.891 ms - Total 47.891 ms / 1 intvls (Avg 47.891 ms)
ts debug: DEMUX_SET_GROUP -1 00000000
ts warning: first packet for pid=257 cc=0xf
ts warning: first packet for pid=770 cc=0x8
ts warning: first packet for pid=258 cc=0xc
main debug: Buffering 0%
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 3%
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 7%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 10%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
mpeg_audio debug: MPGA channels:2 samplerate:48000 bitrate:192
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: Realtek HD Audio 2nd output (Realtek High Definition Audio)
aout_directx debug: found device: Realtek Digital Output (Realtek High Definition Audio)
aout_directx debug: found device: Realtek Digital Output(Optical) (Realtek High Definition Audio)
aout_directx debug: found device: Speakers (Realtek High Definition Audio)
packetizer_h264 warning: waiting for SPS/PPS
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() : 7.259 ms - Total 7.259 ms / 1 intvls (Avg 7.259 ms)
main debug: output 's16l' 48000 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'f32l' 48000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'f32l'->'s16l' 48000 Hz->48000 Hz Stereo->Stereo
aout_directx debug: DirectSoundThread ready
main debug: looking for audio filter module: 13 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.087 ms - Total 0.087 ms / 1 intvls (Avg 0.087 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.062 ms - Total 0.062 ms / 1 intvls (Avg 0.062 ms)
main debug: input 'mpga' 48000 Hz Stereo frame=1152 samples/1161 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 48000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 1440.000 stride_in, 1440 stride_out, 1152 standing, 288 overlap, 672 search, 2400 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.092 ms - Total 0.092 ms / 1 intvls (Avg 0.092 ms)
main debug: filter(s) 'mpga'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
mpgatofixed32 debug: mpga->f32l, bits per sample: 32
main debug: using audio filter module "mpgatofixed32"
main debug: TIMER module_need() : 0.070 ms - Total 0.070 ms / 1 intvls (Avg 0.070 ms)
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 48000 Hz->48000 Hz Stereo->Stereo
main debug: conversion pipeline completed
main debug: filter(s) 'f32l'->'f32l' 52800 Hz->48000 Hz Stereo->Stereo
main debug: looking for audio filter module: 13 candidates
main debug: using audio filter module "samplerate"
main debug: TIMER module_need() : 0.090 ms - Total 0.090 ms / 1 intvls (Avg 0.090 ms)
main debug: conversion pipeline completed
main debug: End of audio preroll
main debug: Buffering 14%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 17%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 21%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 24%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 28%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 31%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 35%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 38%
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 42%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 45%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 49%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 53%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 56%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 60%
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 63%
main debug: Buffering 67%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 70%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 74%
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 77%
main debug: Buffering 78%
packetizer_h264 warning: waiting for SPS/PPS
main debug: Buffering 81%
main debug: Buffering 85%
main debug: Buffering 88%
main debug: Buffering 92%
packetizer_h264 warning: waiting for SPS/PPS
packetizer_h264 debug: found NAL_SPS (sps_id=0)
packetizer_h264 debug: found NAL_PPS (pps_id=0 sps_id=0)
packetizer_h264 debug: Seen SEI recovery point, 0 recovery frames
main debug: Buffering 95%
packetizer_h264 debug: Recovery from SEI recovery point complete
main debug: looking for text renderer module: 2 candidates
freetype debug: Using Arial as font from file C:\Windows\Fonts\ariali.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 1.711 ms - Total 1.711 ms / 1 intvls (Avg 1.711 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.597 ms - Total 0.597 ms / 1 intvls (Avg 0.597 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.165 ms - Total 0.165 ms / 1 intvls (Avg 0.165 ms)
main debug: Deinterlacing available
main debug: deinterlace -1, 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...
qt4 debug: Video was requested 0, 0
main debug: using vout window hwnd module "qt4"
main debug: TIMER module_need() : 12.862 ms - Total 12.862 ms / 1 intvls (Avg 12.862 ms)
direct3d debug: created video sub-window
direct3d debug: Vout EventThread running
direct3d debug: disabling screen saver
direct3d debug: Direct3d Device: NVIDIA GeForce GTX 570 4318 4230 161
main debug: Buffering 99%
main debug: VoutDisplayEvent 'resize' 1063x615 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() : 51.875 ms - Total 51.875 ms / 1 intvls (Avg 51.875 ms)
main debug: original format sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 16:11, msk r0x0 g0x0 b0x0
main debug: removing module "freetype"
main debug: looking for text renderer module: 2 candidates
main debug: VoutDisplayEvent 'resize' 1047x576 window
freetype debug: Using Arial as font from file C:\Windows\Fonts\ariali.ttf
freetype debug: using fontsize: 2
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 0.502 ms - Total 0.502 ms / 1 intvls (Avg 0.502 ms)
avcodec debug: using direct rendering
main debug: End of video preroll
main debug: Received first picture
main debug: Stream buffering done (1030 ms in 1026 ms)
direct3d debug: Created 764x38 texture for OSD
main debug: Post-processing available
main debug: Detected interlaced video
main debug: deinterlace -1, mode blend, is_needed 1
main debug: Adding 'deinterlace' as static
main debug: looking for video filter2 module: 1 candidate
deinterlace debug: using blend deinterlace method
deinterlace debug: deinterlacing
main debug: using video filter2 module "deinterlace"
main debug: TIMER module_need() : 0.136 ms - Total 0.136 ms / 1 intvls (Avg 0.136 ms)
main debug: Filter 'deinterlace' (03be9508) appended to chain
main debug: Decoder buffering done in 49 ms
main warning: PTS is out of range (-35891), dropping buffer
main warning: buffer too late (65906), up-sampling
main warning: audio output out of sync, adjusting dates (61757 us)
main warning: not synchronized (61759 us), resampling
main warning: timing screwed, stopping resampling
main warning: buffer too late (127765), up-sampling
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
main warning: early picture skipped
live555 debug: seek start: 110.000000 stop:899.000000
ts warning: discontinuity indicator (pid=257)
main debug: Buffering 0%
ts warning: discontinuity received 0x1 instead of 0xf (pid=258)
ts warning: discontinuity received 0x1 instead of 0x8 (pid=257)
ts warning: discontinuity received 0xd instead of 0x0 (pid=770)
main debug: Buffering 3%
main debug: Buffering 7%
ts debug: PATCallBack called
main debug: Buffering 10%
main debug: Buffering 14%
main debug: Buffering 17%
main debug: Buffering 21%
main debug: End of audio preroll
main debug: Buffering 24%
main debug: Buffering 28%
main debug: Buffering 31%
main debug: Buffering 33%
main debug: End of video preroll
main debug: Received first picture
main debug: Buffering 37%
main debug: Buffering 40%
main debug: Buffering 44%
main debug: Buffering 48%
main debug: Buffering 51%
main debug: Buffering 55%
main debug: Buffering 58%
main debug: Buffering 62%
main debug: Buffering 65%
main debug: Buffering 69%
main debug: Buffering 72%
main debug: Buffering 76%
main debug: Buffering 79%
main debug: Buffering 83%
main debug: Buffering 86%
main debug: Buffering 90%
main debug: Buffering 94%
main debug: Buffering 97%
main debug: Stream buffering done (1010 ms in 1008 ms)
main debug: Decoder buffering done in 0 ms
main debug: audio output is starving (-166537), playing silence
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
main warning: resampling stopped after 16903927 usec (drift: 40858)
main warning: buffer too early (-40858), down-sampling
main debug: auto hiding mouse cursor
main debug: VoutDisplayEvent 'mouse button' 0 t=8
main debug: VoutDisplayEvent 'mouse button' 0 t=9
main debug: VoutDisplayEvent 'double click'
qt4 debug: Qt4: Fullscreen state changed
qt4 debug: Qt: Entering Fullscreen
qt4 debug: Qt4: Fullscreen state changed
qt4 debug: Qt4: Fullscreen state changed
main debug: VoutDisplayEvent 'resize' 1680x1050 fullscreen
main debug: VoutDisplayEvent 'mouse button' 0 t=8
main debug: VoutDisplayEvent 'mouse button' 0 t=9
main debug: VoutDisplayEvent 'double click'
qt4 debug: Qt4: Fullscreen state changed
qt4 debug: Qt: Quitting Fullscreen
qt4 debug: Qt4: Fullscreen state changed
qt4 debug: Qt4: Fullscreen state changed
main debug: VoutDisplayEvent 'resize' 1664x912 window
main debug: VoutDisplayEvent 'resize' 1047x576 window
main debug: auto hiding mouse cursor
main debug: auto hiding mouse cursor
main debug: incoming request - stopping current input
main debug: dying input
main debug: control: stopping input
ts debug: eof ?
main debug: dying input
main warning: can't get output picture
avcodec warning: disabling direct rendering
main debug: removing module "avcodec"
avcodec debug: ffmpeg codec (H264 - MPEG-4 AVC (part 10)) stopped
main debug: killing decoder fourcc `h264', 51 PES in FIFO
main debug: saving a free vout
main debug: reusing provided vout
main debug: removing module "packetizer_h264"
main debug: removing module "mpeg_audio"
main debug: killing decoder fourcc `mpga', 0 PES in FIFO
main debug: removing module "mpgatofixed32"
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 "live555"
main debug: removing module "ts"
ts debug: pid list:
ts debug: - pid[0] seen
ts debug: - pid[33] seen
ts debug: - pid[257] seen
ts debug: - pid[258] seen
main debug: Program doesn't contain anymore ES
ts debug: - pid[770] seen
ts debug: - pid[8191] seen
main debug: dead input
main debug: destroying useless vout
main debug: removing module "direct3d"
direct3d debug: Direct3D scene released successfully
direct3d debug: DirectXEventThread terminating
direct3d debug: DirectXCloseWindow
direct3d debug: WinProc WM_DESTROY
main debug: Filter 03be9508 removed from chain
main debug: removing module "deinterlace"
qt4 debug: releasing video...
qt4 debug: IM: Deleting the input
main debug: TIMER input launching for 'rtsp://10.253.7.115:554/cup_poptv_10506506.ts' : 95.776 ms - Total 95.776 ms / 1 intvls (Avg 95.776 ms)
qt4 debug: Video is not needed anymore
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"