[vlc-devel] Problems with VOD

Kaarlo Räihä kaarlo.raiha at gmail.com
Mon Jun 13 14:25:52 CEST 2011


2011/6/13 VD <vandhanaa91 at gmail.com>

> I ran live555streamingmedia and tried to stream it through vlc-1.1.7,
> 1.1.9,
> 1.1.10. All of them seem to hang up after a while. I have to kill the
> process
> from task manager in order to close vlc. But i am able to watch the rtsp
> stream
> properly in 1.0.0. i tried to see the log files but did not find much.
>
> Log of Golden Eye:
>
> main debug: adding item `rtsp://192.168.1.145/test.mp3' (
> rtsp://192.168.1.145/test.mp3 )
> qt4 debug: Adding a new MRL to recent ones: rtsp://192.168.1.145/test.mp3
> main debug: rebuilding array of current - root Playlist
> main debug: rebuild done - 1 items, index -1
> main debug: processing request item rtsp://192.168.1.145/test.mp3 node
> null skip
> 0
> main debug: resyncing on rtsp://192.168.1.145/test.mp3
> main debug: rtsp://192.168.1.145/test.mp3 is at 0
> main debug: starting new item
> main debug: creating new input thread
> main debug: Creating an input for 'rtsp://192.168.1.145/test.mp3'
> main debug: thread (input) created at priority 1
> (../.././src/input/input.c:230)
> qt4 debug: IM: Setting an input
> main debug: thread started
> main debug: using timeshift granularity of 50 MBytes
> main debug: using timeshift path 'C:\Users\VANDHA~1\AppData\Local\Temp'
> main debug: `rtsp://192.168.1.145/test.mp3' gives access `rtsp' demux `'
> path
> `192.168.1.145/test.mp3'
> main debug: creating demux: access='rtsp' demux='' path='
> 192.168.1.145/test.mp3'
> main debug: looking for access_demux module: 1 candidate
> qt4 debug: Updating the geometry
> qt4 debug: Updating the geometry
> live555 debug: RTP subsession 'audio/MPA'
> main debug: selecting program id=0
> live555 debug: setup start: 0.000000 stop:174.443000
> live555 debug: We have a timeout of 60 seconds
> live555 debug: spawned timeout thread
> live555 debug: play start: 0.000000 stop:174.443000
> main debug: using access_demux module "live555"
> main debug: TIMER module_need() : 178.000 ms - Total 178.000 ms / 1 intvls
> (Avg
> 178.000 ms)
> main debug: looking for a subtitle file in E:\Program Files\VLC\
> main debug: looking for decoder module: 35 candidates
> main debug: using decoder module "mpeg_audio"
> main debug: TIMER module_need() : 26.000 ms - Total 26.000 ms / 1 intvls
> (Avg
> 26.000 ms)
> main debug: thread (decoder) created at priority 2
> (../.././src/input/decoder.c:315)
> main debug: thread started
> main debug: `rtsp://192.168.1.145/test.mp3' successfully opened
> mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:128
> main debug: Buffering 0%
> main debug: creating aout
> main debug: looking for audio output module: 3 candidates
> aout_directx debug: OpenAudio
> qt4 debug: Updating the geometry
> qt4 debug: Updating the geometry
> qt4 debug: Updating the geometry
> qt4 debug: Updating the geometry
> aout_directx debug: found device: Primary Sound Driver
> aout_directx debug: found device: Speakers (SoundMAX Integrated Digital HD
> Audio)
> qt4 debug: Updating the geometry
> main debug: Buffering 6%
> qt4 debug: Updating the geometry
> qt4 debug: Updating the geometry
> qt4 debug: New caching: 6
> qt4 debug: New caching: 6
> main debug: Buffering 13%
> aout_directx debug: device supports 2 channels (DEFAULT!)
> aout_directx debug: device supports 1 channel
> aout_directx debug: Windows says your SpeakerConfig is stereo
> main debug: Buffering 19%
> aout_directx debug: creating DirectSoundThread
> main debug: thread (DirectSound Notification Thread) created at priority 15
> (../../.././modules/audio_output/directx.c:427)
> main debug: using audio output module "aout_directx"
> main debug: TIMER module_need() : 237.000 ms - Total 237.000 ms / 1 intvls
> (Avg
> 237.000 ms)
> main debug: thread started
> aout_directx debug: DirectSoundThread ready
> main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
> main debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
> main debug: filter(s) 'fl32'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
> main debug: looking for audio filter module: 24 candidates
> qt4 debug: New caching: 19
> qt4 debug: New caching: 19
> main debug: using audio filter module "converter_float"
> main debug: TIMER module_need() : 18.000 ms - Total 18.000 ms / 1 intvls
> (Avg
> 18.000 ms)
> main debug: found a filter for the whole conversion
> main debug: looking for audio mixer module: 3 candidates
> main debug: using audio mixer module "float32_mixer"
> main debug: TIMER module_need() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg
> 2.000 ms)
> main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes
> main debug: looking for audio filter module: 1 candidate
> scaletempo warning: bad input or output format
> main warning: no audio filter module matching "scaletempo" could be loaded
> main debug: TIMER module_need() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg
> 2.000 ms)
> main debug: looking for audio filter module: 1 candidate
> scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
> scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
> scaletempo debug: 1.000 scale, 1323.000 stride_in, 1323 stride_out, 1059
> standing, 264 overlap, 617 search, 2204 queue, fl32 mode
> main debug: using audio filter module "scaletempo"
> main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg
> 1.000 ms)
> main debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo
> main debug: looking for audio filter module: 24 candidates
> main debug: using audio filter module "mpgatofixed32"
> main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg
> 1.000 ms)
> main debug: found a filter for the whole conversion
> main debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo
> main debug: looking for audio filter module: 24 candidates
> main debug: using audio filter module "bandlimited_resampler"
> main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg
> 0.000 ms)
> main debug: found a filter for the whole conversion
> main debug: End of audio preroll
> main debug: Buffering 26%
> main debug: Buffering 32%
> main debug: Buffering 39%
> qt4 debug: New caching: 39
> qt4 debug: New caching: 39
> main debug: Buffering 45%
> qt4 debug: New caching: 45
> qt4 debug: New caching: 45
> main debug: Buffering 52%
> qt4 debug: New caching: 52
> qt4 debug: New caching: 52
> main debug: Buffering 58%
> qt4 debug: New caching: 58
> qt4 debug: New caching: 58
> main debug: Buffering 65%
> qt4 debug: New caching: 65
> qt4 debug: New caching: 65
> main debug: Buffering 71%
> qt4 debug: New caching: 71
> qt4 debug: New caching: 71
> main debug: Buffering 78%
> qt4 debug: New caching: 78
> qt4 debug: New caching: 78
> live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP()
> main error: ES_OUT_RESET_PCR called
> qt4 debug: New caching: 0
> qt4 debug: New caching: 0
> main debug: End of audio preroll
> main debug: Buffering 0%
> main debug: Buffering 6%
> qt4 debug: New caching: 6
> qt4 debug: New caching: 6
> main debug: Buffering 13%
> qt4 debug: New caching: 13
> qt4 debug: New caching: 13
> main debug: Buffering 19%
> qt4 debug: New caching: 19
> qt4 debug: New caching: 19
> main debug: Buffering 26%
> qt4 debug: New caching: 26
> qt4 debug: New caching: 26
> main debug: Buffering 32%
> qt4 debug: New caching: 32
> qt4 debug: New caching: 32
> main debug: Buffering 39%
> qt4 debug: New caching: 39
> qt4 debug: New caching: 39
> main debug: Buffering 45%
> qt4 debug: New caching: 45
> qt4 debug: New caching: 45
> main debug: Buffering 52%
> qt4 debug: New caching: 52
> qt4 debug: New caching: 52
> main debug: Buffering 58%
> qt4 debug: New caching: 58
> qt4 debug: New caching: 58
> main debug: Buffering 65%
> qt4 debug: New caching: 65
> qt4 debug: New caching: 65
> main debug: Buffering 71%
> qt4 debug: New caching: 71
> qt4 debug: New caching: 71
> main debug: Buffering 78%
> qt4 debug: New caching: 78
> qt4 debug: New caching: 78
> main debug: Buffering 84%
> qt4 debug: New caching: 84
> qt4 debug: New caching: 84
> main debug: Buffering 91%
> qt4 debug: New caching: 91
> qt4 debug: New caching: 91
> main debug: Buffering 97%
> qt4 debug: New caching: 97
> qt4 debug: New caching: 97
> main debug: Stream buffering done (1253 ms in 1254 ms)
> main debug: Decoder buffering done in 0 ms
> main warning: PTS is out of range (-10000), dropping buffer
> qt4 debug: New caching: 100
> main warning: PTS is out of range (-36122), dropping buffer
> qt4 debug: New caching: 100
> mpgatofixed32 debug: libmad error: bad main_data_begin pointer
> main warning: buffer is 65219 late, triggering upsampling
> main debug: audio output is too slow (2678756), trashing 50000us
> main debug: audio output is too slow (2628756), trashing 50000us
> main debug: audio output is too slow (2578756), trashing 50000us
> main debug: audio output is too slow (2528756), trashing 50000us
> main debug: audio output is too slow (2478756), trashing 50000us
> main debug: audio output is too slow (2429756), trashing 50000us
> main debug: audio output is too slow (2379756), trashing 50000us
> main debug: audio output is too slow (2329756), trashing 50000us
> main debug: audio output is too slow (2279756), trashing 50000us
> main debug: audio output is too slow (2229756), trashing 50000us
> main debug: audio output is too slow (2179756), trashing 50000us
> main debug: audio output is too slow (2129756), trashing 50000us
> main debug: audio output is too slow (2079756), trashing 50000us
> main debug: audio output is too slow (2029756), trashing 50000us
> main debug: audio output is too slow (1979756), trashing 50000us
> main debug: audio output is too slow (1929756), trashing 50000us
> main debug: audio output is too slow (1879756), trashing 50000us
> main debug: audio output is too slow (1829756), trashing 50000us
> main debug: audio output is too slow (1779756), trashing 50000us
> main debug: audio output is too slow (1729756), trashing 50000us
> main debug: audio output is too slow (1679756), trashing 50000us
> main debug: audio output is too slow (1629756), trashing 50000us
> main debug: audio output is too slow (1579756), trashing 50000us
> main debug: audio output is too slow (1529756), trashing 50000us
> main debug: audio output is too slow (1479756), trashing 50000us
> main debug: audio output is too slow (1430756), trashing 50000us
> main debug: audio output is too slow (1380756), trashing 50000us
> main debug: audio output is too slow (1330756), trashing 50000us
> main debug: audio output is too slow (1280756), trashing 50000us
> main debug: audio output is too slow (1230756), trashing 50000us
> main debug: audio output is too slow (1180756), trashing 50000us
> main debug: audio output is too slow (1130756), trashing 50000us
> main debug: audio output is too slow (1080756), trashing 50000us
> main debug: audio output is too slow (1030756), trashing 50000us
> main debug: audio output is too slow (980756), trashing 50000us
> main debug: audio output is too slow (930756), trashing 50000us
> main debug: audio output is too slow (880756), trashing 50000us
> main debug: audio output is too slow (830756), trashing 50000us
> main debug: audio output is too slow (780756), trashing 50000us
> main debug: audio output is too slow (730756), trashing 50000us
> main debug: audio output is too slow (680756), trashing 50000us
> main debug: audio output is too slow (630756), trashing 50000us
> main debug: audio output is too slow (580756), trashing 50000us
> main debug: audio output is too slow (530756), trashing 50000us
> main debug: audio output is too slow (481756), trashing 50000us
> main debug: audio output is too slow (431756), trashing 50000us
> main debug: audio output is too slow (381756), trashing 50000us
> main debug: audio output is too slow (331756), trashing 50000us
> main debug: audio output is too slow (281756), trashing 50000us
> main debug: audio output is too slow (231756), trashing 50000us
> main debug: audio output is too slow (181756), trashing 50000us
> main debug: audio output is too slow (131756), trashing 50000us
> main debug: audio output is too slow (81756), trashing 50000us
> main warning: output date isn't PTS date, requesting resampling (78756)
> main warning: audio drift is too big (138435), dropping buffer
> main warning: timing screwed, stopping resampling
> main warning: buffer is 112495 late, triggering upsampling
> main warning: output date isn't PTS date, requesting resampling (72977)
> main debug: incoming request - stopping current input
> main debug: dying input
> main warning: audio drift is too big (185293), dropping buffer
> main warning: audio drift is too big (159171), dropping buffer
> main debug: control type=0
> main debug: control: stopping input
> main debug: dying input
> main warning: received buffer in the future
> main debug: thread times: real 0m5.396000s, kernel 0m0.109200s, user
> 0m0.358802s
> main debug: removing module "mpeg_audio"
> main debug: killing decoder fourcc `mpga', 9 PES in FIFO
> main debug: removing module "mpgatofixed32"
> main debug: removing module "scaletempo"
> main debug: removing module "bandlimited_resampler"
> aout_directx debug: closing audio device
> aout_directx debug: DirectSoundThread exiting
> main debug: thread ended
> main debug: thread times: real 0m5.284000s, kernel 0m0.015600s, user
> 0m0.000000s
> main debug: removing module "aout_directx"
> main debug: removing module "converter_float"
> main debug: removing module "float32_mixer"
> main debug: releasing aout
> main debug: removing module "live555"
> main debug: Program doesn't contain anymore ES
> main debug: thread ended
> main debug: dead input
> main debug: thread times: real 0m5.907000s, kernel 0m0.062400s, user
> 0m0.031200s
> qt4 debug: IM: Deleting the input
> qt4 debug: Updating the geometry
> qt4 debug: Updating the geometry
> main debug: TIMER input launching for 'rtsp://192.168.1.145/test.mp3' :
> 256.000
> ms - Total 256.000 ms / 1 intvls (Avg 256.000 ms)
>
>
> Log of Luggage before it hangs up:
>
> qt4 warning: Input option: rtsp-caching=1200
> main debug: adding item `rtsp://192.168.1.145/test.mp3' (
> rtsp://192.168.1.145/test.mp3 )
> qt4 debug: Adding a new MRL to recent ones: rtsp://192.168.1.145/test.mp3
> main debug: rebuilding array of current - root Playlist
> main debug: rebuild done - 1 items, index -1
> main debug: processing request item rtsp://192.168.1.145/test.mp3 node
> null skip
> 0
> main debug: resyncing on rtsp://192.168.1.145/test.mp3
> main debug: rtsp://192.168.1.145/test.mp3 is at 0
> main debug: starting new item
> main debug: creating new input thread
> main debug: Creating an input for 'rtsp://192.168.1.145/test.mp3'
> main debug: meta ok for (null), need to fetch art
> main debug: thread started
> main debug: using timeshift granularity of 50 MiB
> main debug: using timeshift path 'C:\Users\VANDHA~1\AppData\Local\Temp'
> main debug: thread (input) created at priority 1
> (../.././src/input/input.c:220)
> main debug: `rtsp://192.168.1.145/test.mp3' gives access `rtsp' demux `'
> path
> `192.168.1.145/test.mp3'
> main debug: creating demux: access='rtsp' demux='' path='
> 192.168.1.145/test.mp3'
> main debug: looking for access_demux module: 1 candidate
> live555 debug: RTP subsession 'audio/MPA'
> main debug: selecting program id=0
> live555 debug: setup start: 0.000000 stop:174.443000
> qt4 debug: IM: Setting an input
> main debug: looking for meta fetcher module: 1 candidate
> lua debug: Trying Lua scripts in
> C:\Users\VANDHANAA\AppData\Roaming\vlc\lua\meta\fetcher
> lua debug: Trying Lua scripts in E:\Program
> Files\VideoLAN\VLC\lua\meta\fetcher
> lua debug: Trying Lua playlist script E:\Program
> Files\VideoLAN\VLC\lua\meta\fetcher\tvrage.lua
> main debug: using meta fetcher module "lua"
> main debug: TIMER module_need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg
> 3.000 ms)
> main debug: removing module "lua"
> main debug: searching art for rtsp://192.168.1.145/test.mp3
> main debug: looking for art finder module: 2 candidates
> lua debug: Trying Lua scripts in
> C:\Users\VANDHANAA\AppData\Roaming\vlc\lua\meta\art
> lua debug: Trying Lua scripts in E:\Program Files\VideoLAN\VLC\lua\meta\art
> lua debug: Trying Lua playlist script E:\Program
> Files\VideoLAN\VLC\lua\meta\art\01_googleimage.lua
> lua debug: Trying Lua playlist script E:\Program
> Files\VideoLAN\VLC\lua\meta\art\02_frenchtv.lua
> lua debug: Trying Lua playlist script E:\Program
> Files\VideoLAN\VLC\lua\meta\art\03_lastfm.lua
> lua debug: Trying Lua playlist script E:\Program
> Files\VideoLAN\VLC\lua\meta\art\04_musicbrainz.lua
> main debug: no art finder module matching "any" could be loaded
> main debug: TIMER module_need() : 12.000 ms - Total 12.000 ms / 1 intvls
> (Avg
> 12.000 ms)
> main debug: art not found for rtsp://192.168.1.145/test.mp3
> main debug: incoming request - stopping current input
> main debug: dying input
>
> WHat could possibly be the bug? I dint find anyone else complaining about
> this.
> Is this an actual bug or am i doing something wrong? Thanks in advance. :)
>

If VLC hangs (as in you can't control it anymore), it is a bug. But without
debug tools it is usually hard to figure out which component is causing the
problems


>
>
>
>
>
>
> _______________________________________________
> vlc-devel mailing list
> To unsubscribe or modify your subscription options:
> http://mailman.videolan.org/listinfo/vlc-devel
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mailman.videolan.org/pipermail/vlc-devel/attachments/20110613/91501898/attachment.html>


More information about the vlc-devel mailing list