SDP Mpeg4 in twoflower

[dbrb2]

Hi,

I can reliably join a multicast Mpeg4 part 2 stream in VLC 1.17 (Luggage) using an SDP file of the form:

Code: Select all

v=0 m=video <multicast_port> RTP/AVP 96 c=IN <multicast address> a=rtpmap:96 MP4V-ES/90000 a=source-filter: incl IN IP4 <multicast address> <unicast address>

Loaded using the command line:

Code: Select all

vlc -vvv -extraintf=logger --logfile=log.vlc video.sdp

The video will start playing almost immediately, and the debug log, from joining the stream to stopping it a few seconds later, gives:

Code: Select all

-- logger module started -- main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. avcodec: obtained IDirect3DDeviceManager9 avcodec: DXVA2CreateVideoService Success! -- logger module started -- main debug: using interface module "logger" main debug: TIMER module_need() : 18.000 ms - Total 18.000 ms / 1 intvls (Avg 18.000 ms) main debug: looking for interface module: 1 candidate main debug: using interface module "hotkeys" main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms) main debug: Activated main debug: rebuilding array of current - root Playlist main debug: adding item `VLC_1.17/video.sdp' ( file:///C:/Program%20Files/VideoLAN/VLC_1.17%2Fvideo.sdp ) main debug: rebuild done - 0 items, index -1 main debug: looking for interface module: 1 candidate main debug: Creating an input for 'VLC_1.17/video.sdp' main debug: using interface module "globalhotkeys" main debug: TIMER module_need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms) main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main debug: looking for interface module: 4 candidates main debug: no fetch required for (null) (art currently (null)) main debug: looking for extension module: 1 candidate lua debug: Opening EXPERIMENTAL Lua Extension module lua debug: Trying Lua scripts in C:\Users\ben\AppData\Roaming\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC_1.17\lua\extensions lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC_1.17\lua\extensions\allocine-fr.lua lua debug: Scanning Lua script C:\Program Files\VideoLAN\VLC_1.17\lua\extensions\allocine-fr.lua lua debug: Script C:\Program Files\VideoLAN\VLC_1.17\lua\extensions\allocine-fr.lua has the following capability flags: 0xc main debug: using extension module "lua" main debug: TIMER module_need() : 8.000 ms - Total 8.000 ms / 1 intvls (Avg 8.000 ms) main debug: using interface module "qt4" main debug: TIMER module_need() : 67.000 ms - Total 67.000 ms / 1 intvls (Avg 67.000 ms) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: processing request item null node Playlist skip 0 main debug: starting new item main debug: creating new input thread main debug: Creating an input for 'VLC_1.17/video.sdp' main debug: thread (input) created at priority 1 (../.././src/input/input.c:214) main debug: thread started main debug: using timeshift granularity of 50 MiB main debug: using timeshift path 'C:\Users\ben\AppData\Local\Temp' main debug: `file:///C:/Program%20Files/VideoLAN/VLC_1.17%2Fvideo.sdp' gives access `file' demux `' path `C:\Program Files\VideoLAN\VLC_1.17\video.sdp' main debug: creating demux: access='file' demux='' path='C:\Program Files\VideoLAN\VLC_1.17\video.sdp' main debug: looking for access_demux module: 2 candidates main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: creating access 'file' path='C:\Program Files\VideoLAN\VLC_1.17\video.sdp' main debug: looking for access module: 2 candidates filesystem debug: opening file `C:\Program Files\VideoLAN\VLC_1.17\video.sdp' main debug: using access module "filesystem" main debug: TIMER module_need() : 6.000 ms - Total 6.000 ms / 1 intvls (Avg 6.000 ms) main debug: Using AStream*Stream main debug: pre buffering main debug: received first data after 0 ms main debug: looking for stream_filter module: 2 candidates main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms) main debug: creating demux: access='file' demux='' path='C:\Program Files\VideoLAN\VLC_1.17\video.sdp' main debug: looking for demux module: 52 candidates live555 debug: RTP subsession 'video/MP4V-ES' main debug: selecting program id=0 qt4 debug: IM: Setting an input live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: play start: 0.000000 stop:0.000000 main debug: using demux module "live555" main debug: TIMER module_need() : 11.000 ms - Total 11.000 ms / 1 intvls (Avg 11.000 ms) main debug: looking for a subtitle file in C:\Program Files\VideoLAN\VLC_1.17\ main debug: looking for decoder module: 34 candidates avcodec debug: libavcodec initialized (interface 0x346c00) avcodec debug: trying to use direct rendering avcodec debug: Available decoder output format 61 (PIX_FMT_DXVA2_VLD) avcodec debug: Trying DXVA2 avcodec debug: DLLs loaded avcodec debug: D3dCreateDevice succeed avcodec debug: OurDirect3DCreateDeviceManager9 Success! avcodec: obtained IDirect3DDeviceManager9 avcodec: DXVA2CreateVideoService Success! avcodec debug: - 'DXVA2_ModeMPEG2_IDCT' is supported by hardware avcodec debug: - 'DXVA2_ModeMPEG2_MoComp' is supported by hardware avcodec warning: - Unknown GUID = BCC5DB6D-a2b6-4af0-XXXX avcodec debug: - 'H.264 VLD, no FGT (Intel)' is supported by hardware avcodec warning: - Unknown GUID = A74CCAE2-f466-45ae-XXXX avcodec warning: - Unknown GUID = 07460004-7533-4e1a-XXXX avcodec debug: - 'Windows Media Video 9 MoComp' is supported by hardware avcodec debug: - 'Windows Media Video 9 IDCT' is supported by hardware avcodec debug: - 'VC-1 MoComp' is supported by hardware avcodec debug: - 'VC-1 IDCT' is supported by hardware avcodec debug: - 'H.264 motion compensation (MoComp), no FGT (Intel)' is supported by hardware avcodec debug: - 'H.264 inverse discrete cosine transform (IDCT), no FGT (Intel)' is supported by hardware avcodec error: DxFindVideoServiceConversion failed avcodec warning: Failed to open DXVA2 avcodec debug: Available decoder output format 53 (PIX_FMT_VAAPI_VLD) avcodec debug: Available decoder output format 0 (PIX_FMT_YUV420P) avcodec debug: ffmpeg codec (MPEG-4 Video) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 79.000 ms - Total 79.000 ms / 1 intvls (Avg 79.000 ms) main debug: thread started main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:301) main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Users\ben\AppData\Roaming\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC_1.17\lua\meta\reader lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC_1.17\lua\meta\reader\filename.lua main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 9.000 ms - Total 9.000 ms / 1 intvls (Avg 9.000 ms) main debug: `file:///C:/Program%20Files/VideoLAN/VLC_1.17%2Fvideo.sdp' successfully opened main debug: Buffering 0% main debug: Buffering 13% main debug: Buffering 26% avcodec warning: disabling direct rendering avcodec warning: cannot decode one frame (13780 bytes) avcodec warning: cannot decode one frame (27752 bytes) avcodec warning: cannot decode one frame (13896 bytes) main debug: Buffering 40% avcodec warning: cannot decode one frame (27681 bytes) main debug: Buffering 53% avcodec warning: cannot decode one frame (13702 bytes) main debug: Buffering 66% avcodec warning: cannot decode one frame (27580 bytes) live555 debug: lost 264 bytes live555 debug: increasing buffer size to 131072 main debug: Buffering 80% main debug: no usable vout present, spawning one main debug: looking for text renderer module: 2 candidates freetype debug: Building font databases. freetype debug: Took 3000 microseconds freetype debug: Using Arial as font from file C:/Windows/fonts/arial.ttf freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 11.000 ms - Total 11.000 ms / 1 intvls (Avg 11.000 ms) main debug: looking for video filter2 module: 18 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 5.000 ms - Total 5.000 ms / 1 intvls (Avg 5.000 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() : 6.000 ms - Total 6.000 ms / 1 intvls (Avg 6.000 ms) main debug: window size: 720x576 main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: looking for video output module: 1 candidate vout_wrapper debug: Opening vout display wrapper main debug: looking for vout display module: 8 candidates main debug: Buffering 80% direct3d debug: creating Vout EventThread direct3d debug: DirectXCreateWindow main debug: looking for vout window hwnd module: 3 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() : 14.000 ms - Total 14.000 ms / 1 intvls (Avg 14.000 ms) direct3d debug: created video sub-window main debug: Buffering 93% direct3d debug: Vout EventThread running direct3d debug: disabling screen saver main debug: Stream buffering done (320 ms in 355 ms) main debug: VoutDisplayEvent 'resize' 728x603 window direct3d warning: trying surface pixel format: YV12 direct3d debug: selected surface pixel format is YV12 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() : 78.000 ms - Total 78.000 ms / 1 intvls (Avg 78.000 ms) main debug: using video output module "vout_wrapper" main debug: TIMER module_need() : 86.000 ms - Total 86.000 ms / 1 intvls (Avg 86.000 ms) main debug: got 1 direct buffer(s) main debug: pic render sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 main debug: pic in sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 main debug: pic out sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 1:1, msk r0x0 g0x0 b0x0 main debug: direct render, mapping render pictures 0-23 to system pictures 1-24 main debug: VoutDisplayEvent 'resize' 720x576 window avcodec debug: using direct rendering main debug: End of video preroll main debug: Received first picture freetype debug: using fontsize: 36 avcodec warning: cannot decode one frame (26808 bytes) main debug: looking for video blending module: 1 candidate blend debug: chroma: YUVA -> I420 main debug: Decoder buffering done in 59 ms main debug: using video blending module "blend" main debug: TIMER module_need() : 9.000 ms - Total 9.000 ms / 1 intvls (Avg 9.000 ms) main debug: Post-processing available main warning: vlc_object_find_name(postproc) is not safe! main debug: auto hidding mouse main debug: auto hidding mouse main debug: auto hidding mouse main debug: auto hidding mouse main debug: auto hidding mouse main debug: incoming request - stopping current input main debug: dying input main debug: control type=0 main debug: control: stopping input main debug: dying input main debug: thread times: real 0m20.434168s, kernel 0m0.000000s, user 0m0.686404s avcodec debug: ffmpeg codec (MPEG-4 Video) stopped main debug: removing module "avcodec" main debug: killing decoder fourcc `mp4v', 0 PES in FIFO main debug: [0] 0 0 main debug: [1] 0 0 main debug: [2] 0 0 main debug: [3] 0 0 main debug: [4] 0 0 main debug: [5] 0 0 main debug: [6] 0 0 main debug: [7] 0 0 main debug: [8] 0 0 main debug: [9] 0 0 main debug: [10] 0 0 main debug: [11] 2 0 main debug: [12] 4 0 main debug: [13] 4 0 main debug: [14] 2 0 main debug: [15] 2 0 main debug: [16] 4 0 main debug: [17] 2 0 main debug: [18] 2 0 main debug: [19] 2 0 main debug: [20] 2 0 main debug: [21] 2 0 main debug: [22] 2 0 main debug: [23] 2 0 main debug: saving a free vout main debug: removing module "live555" main debug: removing module "stream_filter_record" main debug: removing module "filesystem" main debug: Program doesn't contain anymore ES main debug: thread ended main debug: dead input main debug: thread times: real 0m20.602178s, kernel 0m4.836031s, user 0m1.778411s main debug: destroying useless vout qt4 debug: IM: Deleting the input main debug: TIMER input launching for 'VLC_1.17/video.sdp' : 159.000 ms - Total 159.000 ms / 1 intvls (Avg 159.000 ms) direct3d debug: Direct3D scene released successfully direct3d debug: DirectXEventThread terminating direct3d debug: DirectXCloseWindow direct3d debug: WinProc WM_DESTROY qt4 debug: releasing video... qt4 debug: Video is not needed anymore main debug: removing module "qt4" main debug: removing module "direct3d" main debug: removing module "vout_wrapper" main debug: removing module "blend" main debug: removing module "freetype" main debug: removing module "yuvp" main debug: removing module "swscale"

If I try to play exactly the same SDP file with twoflower (2.0.6), the multicast group is still joined - wireshark shows the video traffic arriving, but no video is displayed. The debug log gives:

Code: Select all

 -- logger module started -- main debug: using interface module "logger" main debug: TIMER module_need() : 29.799 ms - Total 29.799 ms / 1 intvls (Avg 29.799 ms) main debug: looking for interface module: 1 candidate main debug: using interface module "hotkeys" main debug: TIMER module_need() : 2.241 ms - Total 2.241 ms / 1 intvls (Avg 2.241 ms) main debug: looking for interface module: 1 candidate logger: VLC media player - 2.0.6 Twoflower logger: Copyright © 1996-2013 VLC authors and VideoLAN logger: Warning: if you cannot access the GUI anymore, open a command-line window, go to the directory where you installed VLC and run "vlc -I qt" logger: using logger. logger debug: opening logfile `vlcdebug.txt' -- logger module started -- main debug: using interface module "logger" main debug: using interface module "logger" main debug: TIMER module_need() : 7.792 ms - Total 7.792 ms / 1 intvls (Avg 7.792 ms) main debug: TIMER module_need() : 7.792 ms - Total 7.792 ms / 1 intvls (Avg 7.792 ms) main debug: playlist threads correctly activated main debug: playlist threads correctly activated main debug: adding item `video.sdp' ( file:///C:/tfl/video.sdp ) main debug: adding item `video.sdp' ( file:///C:/tfl/video.sdp ) main debug: rebuilding array of current - root Playlist main debug: rebuilding array of current - root Playlist main debug: rebuild done - 0 items, index -1 main debug: rebuild done - 0 items, index -1 main debug: looking for interface module: 1 candidate main debug: looking for interface module: 1 candidate main debug: Creating an input for 'video.sdp' main debug: Creating an input for 'video.sdp' main debug: using interface module "globalhotkeys" main debug: using interface module "globalhotkeys" main debug: TIMER module_need() : 7.297 ms - Total 7.297 ms / 1 intvls (Avg 7.297 ms) main debug: TIMER module_need() : 7.297 ms - Total 7.297 ms / 1 intvls (Avg 7.297 ms) main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main: Running vlc with the default interface. Use 'cvlc' to use vlc without interface. main debug: looking for interface module: 4 candidates main debug: looking for interface module: 4 candidates main debug: no fetch required for (null) (art currently (null)) main debug: no fetch required for (null) (art currently (null)) main debug: looking for extension module: 1 candidate main debug: looking for extension module: 1 candidate lua debug: Opening Lua Extension module lua debug: Opening Lua Extension module lua debug: Trying Lua scripts in C:\Users\ben\AppData\Roaming\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Users\ben\AppData\Roaming\vlc\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\extensions lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\VLC\lua\extensions main debug: using extension module "lua" main debug: using extension module "lua" main debug: TIMER module_need() : 10.378 ms - Total 10.378 ms / 1 intvls (Avg 10.378 ms) main debug: TIMER module_need() : 10.378 ms - Total 10.378 ms / 1 intvls (Avg 10.378 ms) main debug: using interface module "qt4" main debug: using interface module "qt4" main debug: TIMER module_need() : 95.529 ms - Total 95.529 ms / 1 intvls (Avg 95.529 ms) main debug: TIMER module_need() : 95.529 ms - Total 95.529 ms / 1 intvls (Avg 95.529 ms) main debug: rebuilding array of current - root Playlist main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: rebuild done - 1 items, index -1 main debug: processing request item: null, node: Playlist, skip: 0 main debug: processing request item: null, node: Playlist, skip: 0 main debug: starting playback of the new playlist item main debug: starting playback of the new playlist item main debug: resyncing on video.sdp main debug: resyncing on video.sdp main debug: video.sdp is at 0 main debug: video.sdp is at 0 main debug: creating new input thread main debug: creating new input thread main debug: Creating an input for 'video.sdp' main debug: Creating an input for 'video.sdp' qt4 debug: IM: Setting an input qt4 debug: IM: Setting an input main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\ben\AppData\Local\Temp' main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\ben\AppData\Local\Temp' main debug: `file:///C:/tfl/video.sdp' gives access `file' demux `' path `/C:/tfl/video.sdp' main debug: `file:///C:/tfl/video.sdp' gives access `file' demux `' path `/C:/tfl/video.sdp' main debug: creating demux: access='file' demux='' location='/C:/tfl/video.sdp' file='C:\tfl\video.sdp' main debug: creating demux: access='file' demux='' location='/C:/tfl/video.sdp' file='C:\tfl\video.sdp' main debug: looking for access_demux module: 3 candidates main debug: looking for access_demux module: 3 candidates main debug: no access_demux module matching "file" could be loaded main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 5.690 ms - Total 5.690 ms / 1 intvls (Avg 5.690 ms) main debug: TIMER module_need() : 5.690 ms - Total 5.690 ms / 1 intvls (Avg 5.690 ms) main debug: creating access 'file' location='/C:/tfl/video.sdp', path='C:\tfl\video.sdp' main debug: creating access 'file' location='/C:/tfl/video.sdp', path='C:\tfl\video.sdp' main debug: looking for access module: 3 candidates main debug: looking for access module: 3 candidates filesystem debug: opening file `C:\tfl\video.sdp' filesystem debug: opening file `C:\tfl\video.sdp' main debug: using access module "filesystem" main debug: using access module "filesystem" main debug: TIMER module_need() : 6.740 ms - Total 6.740 ms / 1 intvls (Avg 6.740 ms) main debug: TIMER module_need() : 6.740 ms - Total 6.740 ms / 1 intvls (Avg 6.740 ms) main debug: Using stream method for AStream* main debug: Using stream method for AStream* main debug: starting pre-buffering main debug: starting pre-buffering main debug: received first data after 0 ms main debug: received first data after 0 ms main debug: looking for stream_filter module: 4 candidates main debug: looking for stream_filter module: 4 candidates main debug: no stream_filter module matching "any" could be loaded main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 5.578 ms - Total 5.578 ms / 1 intvls (Avg 5.578 ms) main debug: TIMER module_need() : 5.578 ms - Total 5.578 ms / 1 intvls (Avg 5.578 ms) main debug: looking for stream_filter module: 1 candidate main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 4.523 ms - Total 4.523 ms / 1 intvls (Avg 4.523 ms) main debug: TIMER module_need() : 4.523 ms - Total 4.523 ms / 1 intvls (Avg 4.523 ms) main debug: creating demux: access='file' demux='' location='/C:/tfl/video.sdp' file='C:\tfl\video.sdp' main debug: creating demux: access='file' demux='' location='/C:/tfl/video.sdp' file='C:\tfl\video.sdp' main debug: looking for demux module: 55 candidates main debug: looking for demux module: 55 candidates live555 debug: version 2012.12.18 live555 debug: version 2012.12.18 live555 debug: RTP subsession 'video/MP4V-ES' live555 debug: RTP subsession 'video/MP4V-ES' main debug: selecting program id=0 main debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: play start: 0.000000 stop:0.000000 live555 debug: play start: 0.000000 stop:0.000000 main debug: using demux module "live555" main debug: using demux module "live555" main debug: TIMER module_need() : 26.648 ms - Total 26.648 ms / 1 intvls (Avg 26.648 ms) main debug: TIMER module_need() : 26.648 ms - Total 26.648 ms / 1 intvls (Avg 26.648 ms) main debug: looking for a subtitle file in C:\tfl\ main debug: looking for a subtitle file in C:\tfl\ main debug: looking for decoder module: 32 candidates main debug: looking for decoder module: 32 candidates avcodec debug: libavcodec initialized (interface 0x362901) avcodec debug: libavcodec initialized (interface 0x362901) avcodec debug: trying to use direct rendering avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: allowing 1 thread(s) for decoding avcodec debug: ffmpeg codec (MPEG-4 Video) started avcodec debug: ffmpeg codec (MPEG-4 Video) started main debug: using decoder module "avcodec" main debug: using decoder module "avcodec" main debug: TIMER module_need() : 21.652 ms - Total 21.652 ms / 1 intvls (Avg 21.652 ms) main debug: TIMER module_need() : 21.652 ms - Total 21.652 ms / 1 intvls (Avg 21.652 ms) main debug: looking for meta reader module: 2 candidates main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Users\ben\AppData\Roaming\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Users\ben\AppData\Roaming\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Program Files\VideoLAN\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 lua debug: Trying Lua playlist script C:\Program Files\VideoLAN\VLC\lua\meta\reader\filename.luac main debug: no meta reader module matching "any" could be loaded main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 19.433 ms - Total 19.433 ms / 1 intvls (Avg 19.433 ms) main debug: TIMER module_need() : 19.433 ms - Total 19.433 ms / 1 intvls (Avg 19.433 ms) main debug: `file:///C:/tfl/video.sdp' successfully opened main debug: `file:///C:/tfl/video.sdp' successfully opened main debug: Buffering 0% main debug: Buffering 0% main debug: Buffering 4% main debug: Buffering 4% main debug: Buffering 8% main debug: Buffering 8% main debug: Buffering 12% main debug: Buffering 12% main debug: Buffering 16% main debug: Buffering 16% main debug: Buffering 20% main debug: Buffering 20% main debug: Buffering 24% main debug: Buffering 24% main debug: Buffering 28% main debug: Buffering 28% main debug: Buffering 32% main debug: Buffering 32% main debug: Buffering 36% main debug: Buffering 36% main debug: Buffering 40% main debug: Buffering 40% main debug: Buffering 44% main debug: Buffering 44% main debug: Buffering 48% main debug: Buffering 48% main debug: Buffering 52% main debug: Buffering 52% main debug: Buffering 56% main debug: Buffering 56% main debug: Buffering 60% main debug: Buffering 60% main debug: Buffering 64% main debug: Buffering 64% main debug: Buffering 68% main debug: Buffering 68% main debug: Buffering 72% main debug: Buffering 72% main debug: Buffering 76% main debug: Buffering 76% main debug: Buffering 80% main debug: Buffering 80% main debug: Buffering 84% main debug: Buffering 84% main debug: Buffering 88% main debug: Buffering 88% live555 debug: lost 17425 bytes live555 debug: lost 17425 bytes live555 debug: increasing buffer size to 131072 live555 debug: increasing buffer size to 131072 main debug: Buffering 92% main debug: Buffering 92% main debug: Buffering 96% main debug: Buffering 96% main debug: Buffering 100% main debug: Buffering 100% main debug: Stream buffering done (1040 ms in 991 ms) main debug: Stream buffering done (1040 ms in 991 ms) main debug: incoming request - stopping current input main debug: incoming request - stopping current input main debug: dying input main debug: dying input main debug: exiting main debug: exiting main debug: deactivating the playlist main debug: deactivating the playlist main debug: deactivating the playlist main debug: deactivating the playlist main debug: dying input main debug: dying input

I have found some people suggesting setting the FFMPEG threads explicitly can help here, but for me so far it has nto made any difference. Can someone suggest possible causes / fixes?

[dbrb2]

A bit of progress....

This thread here:
viewtopic.php?f=14&t=100647

Suggested setting ffmpeg threads to one. When running v2.06, this had no effect.
However,this thread here:

viewtopic.php?f=14&t=105792

Suggested that something might have changed between 2.03 and 2.06 to affect mpeg4 playback. I downgraded to 2.03, and with the default ffmpeg threads value of zero (automatic) I still had no luck. But combining these two suggestions, and setting threads to 1 whilst using v2.03, works fine

Any ideas as to why...?

[Jean-Baptiste Kempf]

Probably a live555 regression doubled to the ffmpeg thread issue.

[dbrb2]

Oh cool. Does that mean there is an existing open issue fo this? I wasn't sure... Do you have the issue number? I didn't want to raise a new one if it was already known about...

[dbrb2]

Do you know if anything ever came of either of these issues, or do they not affect very many people?

I have tried the latest nightly and the problem seems the same, though I am not sure the cause is...

Any attempt to open an SDP file now to join a multicast stream gives:

Code: Select all

main debug: VLC media player - 2.2.0-git-20130712-1017 Weatherwax main debug: Copyright © 1996-2013 the VideoLAN team main debug: revision 2.1.0-git-168-g7d4c543 main debug: configured with ../extras/package/win32/../../../configure '--enable-update-check' '--enable-lua' '--enable-faad' '--enable-flac' '--enable-theora' '--enable-twolame' '--enable-quicktime' '--enable-avcodec' '--enable-merge-ffmpeg' '--enable-dca' '--enable-mpc' '--enable-libass' '--enable-x264' '--enable-schroedinger' '--enable-realrtsp' '--enable-live555' '--enable-dvdread' '--enable-shout' '--enable-goom' '--enable-caca' '--disable-sdl' '--enable-qt' '--enable-skins2' '--enable-sse' '--enable-mmx' '--enable-libcddb' '--enable-zvbi' '--disable-telx' '--enable-nls' '--disable-dirac' '--host=i686-w64-mingw32' '--enable-debug' '--disable-chromaprint' 'host_alias=i686-w64-mingw32' main debug: adding item `test.sdp' ( file:///C:/test.sdp ) qt4 debug: Adding a new MRL to recent ones: file:///C:/test.sdp main debug: incoming request - stopping current input main debug: Creating an input for 'test.sdp' main debug: meta ok for (null), need to fetch art main debug: looking for meta fetcher module matching "any": 1 candidates lua debug: Trying Lua scripts in C:\Users\Admin\AppData\Roaming\vlc\lua\meta\fetcher lua debug: Trying Lua scripts in C:\Users\Admin\Desktop\vlc_nightly\lua\meta\fetcher lua debug: Trying Lua playlist script C:\Users\Admin\Desktop\vlc_nightly\lua\meta\fetcher\tvrage.luac main debug: using meta fetcher module "lua" main debug: removing module "lua" main debug: searching art for test.sdp main debug: looking for art finder module matching "any": 2 candidates lua debug: Trying Lua scripts in C:\Users\Admin\AppData\Roaming\vlc\lua\meta\art lua debug: Trying Lua scripts in C:\Users\Admin\Desktop\vlc_nightly\lua\meta\art lua debug: Trying Lua playlist script C:\Users\Admin\Desktop\vlc_nightly\lua\meta\art\00_musicbrainz.luac lua debug: Trying Lua playlist script C:\Users\Admin\Desktop\vlc_nightly\lua\meta\art\01_googleimage.luac lua debug: Trying Lua playlist script C:\Users\Admin\Desktop\vlc_nightly\lua\meta\art\02_frenchtv.luac lua debug: Trying Lua playlist script C:\Users\Admin\Desktop\vlc_nightly\lua\meta\art\03_lastfm.luac main debug: no art finder modules matched main debug: art not found for test.sdp

[Jean-Baptiste Kempf]

Your logs are incomplete.

[dbrb2]

They are? Sorry about that. Below are two log files, one from 1.1.7 (Luggage) that works fine, and one from the latest nightly, which seems to have the same problem as the lates stable. The same SDP (of the format given at the top of this thread) was passed to both.

Luggage:

Code: Select all

main debug: adding item `test.sdp' ( file:///C:/test.sdp ) qt4 debug: Adding a new MRL to recent ones: C:\test.sdp main debug: rebuilding array of current - root Playlist main debug: Creating an input for 'test.sdp' main debug: rebuild done - 1 items, index -1 main debug: processing request item test.sdp node null skip 0 main debug: resyncing on test.sdp main debug: test.sdp is at 0 main debug: starting new item main debug: creating new input thread main debug: Creating an input for 'test.sdp' main debug: thread started main debug: using timeshift granularity of 50 MiB main debug: using timeshift path 'C:\Users\Admin\AppData\Local\Temp' main debug: thread (input) created at priority 1 (../.././src/input/input.c:214) main debug: `file:///C:/tfl/test.sdp' gives access `file' demux `' path `C:\test.sdp' main debug: creating demux: access='file' demux='' path='C:\test.sdp' main debug: looking for access_demux module: 2 candidates main debug: no access_demux module matching "file" could be loaded main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms) main debug: creating access 'file' path='C:\test.sdp' main debug: looking for access module: 2 candidates filesystem debug: opening file `C:\test.sdp' main debug: using access module "filesystem" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: Using AStream*Stream main debug: pre buffering main debug: received first data after 0 ms main debug: pre-buffering done 132 bytes in 0s - 128906 KiB/s main debug: looking for stream_filter module: 2 candidates main debug: no stream_filter module matching "any" could be loaded main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for stream_filter module: 1 candidate main debug: using stream_filter module "stream_filter_record" main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating demux: access='file' demux='' path='C:\test.sdp' main debug: looking for demux module: 52 candidates live555 debug: RTP subsession 'video/MP4V-ES' main debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: play start: 0.000000 stop:0.000000 main debug: using demux module "live555" main debug: TIMER module_need() : 7.000 ms - Total 7.000 ms / 1 intvls (Avg 7.000 ms) main debug: looking for a subtitle file in C:\tfl\ main debug: looking for decoder module: 34 candidates main debug: no fetch required for (null) (art currently (null)) avcodec debug: libavcodec initialized (interface 0x346c00) avcodec debug: trying to use direct rendering avcodec debug: ffmpeg codec (MPEG-4 Video) started main debug: using decoder module "avcodec" main debug: TIMER module_need() : 12.000 ms - Total 12.000 ms / 1 intvls (Avg 12.000 ms) qt4 debug: IM: Setting an input main debug: thread started main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:301) main debug: looking for meta reader module: 2 candidates lua debug: Trying Lua scripts in C:\Users\Admin\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.lua main debug: no meta reader module matching "any" could be loaded main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms) main debug: `file:///C:/tfl/test.sdp' successfully opened main debug: Buffering 0% avcodec warning: disabling direct rendering avcodec warning: cannot decode one frame (13633 bytes) main debug: Buffering 27% avcodec warning: cannot decode one frame (19315 bytes) main debug: Buffering 40% main debug: Buffering 53% main debug: Buffering 67% live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP() main debug: ES_OUT_RESET_PCR called main debug: Buffering 0% avcodec warning: cannot decode one frame (19212 bytes) main debug: Buffering 13% avcodec warning: cannot decode one frame (19493 bytes) main debug: Buffering 26% avcodec warning: cannot decode one frame (19583 bytes) main debug: Buffering 40% avcodec warning: cannot decode one frame (19330 bytes) main debug: Buffering 53% avcodec warning: cannot decode one frame (19433 bytes) main debug: Buffering 66% avcodec warning: cannot decode one frame (19964 bytes) main debug: Buffering 80% avcodec warning: cannot decode one frame (19636 bytes) main debug: Buffering 93% avcodec warning: cannot decode one frame (18142 bytes) main debug: Stream buffering done (320 ms in 321 ms) avcodec warning: cannot decode one frame (19236 bytes) main debug: Decoder buffering done in 0 ms avcodec warning: cannot decode one frame (19193 bytes) live555 debug: lost 355 bytes live555 debug: increasing buffer size to 131072 main debug: no usable vout present, spawning one main debug: looking for text renderer module: 2 candidates freetype debug: Building font databases. freetype debug: Took 9000 microseconds freetype debug: Using Arial as font from file C:/Windows/fonts/arial.ttf freetype debug: using fontsize: 2 main debug: using text renderer module "freetype" main debug: TIMER module_need() : 12.000 ms - Total 12.000 ms / 1 intvls (Avg 12.000 ms) main debug: looking for video filter2 module: 18 candidates swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality) main debug: using video filter2 module "swscale" main debug: TIMER module_need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 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() : 2.000 ms - Total 2.000 ms / 1 intvls (Avg 2.000 ms) main debug: window size: 768x576 main debug: Deinterlacing available main debug: deinterlace 0, mode blend, is_needed 0 main debug: looking for video output module: 1 candidate vout_wrapper debug: Opening vout display wrapper main debug: looking for vout display module: 8 candidates direct3d debug: creating Vout EventThread direct3d debug: DirectXCreateWindow main debug: looking for vout window hwnd module: 3 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() : 9.000 ms - Total 9.000 ms / 1 intvls (Avg 9.000 ms) direct3d debug: created video sub-window direct3d debug: Vout EventThread running direct3d debug: disabling screen saver main debug: VoutDisplayEvent 'resize' 784x614 window direct3d warning: trying surface pixel format: YV12 direct3d debug: selected surface pixel format is YV12 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() : 125.000 ms - Total 125.000 ms / 1 intvls (Avg 125.000 ms) main debug: using video output module "vout_wrapper" main debug: TIMER module_need() : 125.000 ms - Total 125.000 ms / 1 intvls (Avg 125.000 ms) main debug: got 1 direct buffer(s) main debug: pic render sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 16:15, msk r0x0 g0x0 b0x0 main debug: pic in sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 16:15, msk r0x0 g0x0 b0x0 main debug: pic out sz 720x576, of (0,0), vsz 720x576, 4cc I420, sar 16:15, msk r0x0 g0x0 b0x0 main debug: direct render, mapping render pictures 0-23 to system pictures 1-24 main debug: VoutDisplayEvent 'resize' 768x576 window avcodec debug: using direct rendering main debug: End of video preroll avcodec warning: cannot decode one frame (3533 bytes) freetype debug: using fontsize: 36 main debug: looking for video blending module: 1 candidate blend debug: chroma: YUVA -> I420 main debug: using video blending module "blend" main debug: TIMER module_need() : 1.000 ms - Total 1.000 ms / 1 intvls (Avg 1.000 ms) main debug: Post-processing available main warning: vlc_object_find_name(postproc) is not safe!

At this point the video is streaming fine


2.2.0-git-20130707-0003 Weatherwax

Code: Select all

main debug: VLC media player - 2.2.0-git-20130707-0003 Weatherwax main debug: Copyright © 1996-2013 the VideoLAN team main debug: revision 2.1.0-git-55-g36a00fd main debug: configured with ../extras/package/win32/../../../configure '--enable-update-check' '--enable-lua' '--enable-faad' '--enable-flac' '--enable-theora' '--enable-twolame' '--enable-quicktime' '--enable-avcodec' '--enable-merge-ffmpeg' '--enable-dca' '--enable-mpc' '--enable-libass' '--enable-x264' '--enable-schroedinger' '--enable-realrtsp' '--enable-live555' '--enable-dvdread' '--enable-shout' '--enable-goom' '--enable-caca' '--disable-sdl' '--enable-qt' '--enable-skins2' '--enable-sse' '--enable-mmx' '--enable-libcddb' '--enable-zvbi' '--disable-telx' '--enable-nls' '--disable-dirac' '--host=i686-w64-mingw32' '--enable-debug' '--disable-chromaprint' 'host_alias=i686-w64-mingw32' main debug: adding item `test.sdp' ( file:///C:/test.sdp ) qt4 debug: Adding a new MRL to recent ones: file:///C:/test.sdp main debug: Creating an input for 'test.sdp' main debug: processing request item: test.sdp, node: null, skip: 0 main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index 0 main debug: starting playback of the new playlist item main debug: resyncing on test.sdp main debug: test.sdp is at 0 main debug: creating new input thread main debug: Creating an input for 'test.sdp' main debug: using timeshift granularity of 50 MiB, in path 'C:\Users\Admin\AppData\Local\Temp' main debug: `file:///C:/test.sdp' gives access `file' demux `' path `/C:/test.sdp' main debug: creating demux: access='file' demux='' location='/C:/test.sdp' file='C:\test.sdp' main debug: looking for access_demux module matching "file": 11 candidates main debug: no access_demux modules matched main debug: creating access 'file' location='/C:/test.sdp', path='C:\tfl\test.sdp' main debug: looking for access module matching "file": 18 candidates filesystem debug: opening file `C:\test.sdp' main debug: using access module "filesystem" main debug: Using stream method for AStream* main debug: starting pre-buffering main debug: received first data after 1 ms main debug: pre-buffering done 132 bytes in 0s - 128 KiB/s main debug: looking for stream_filter module matching "any": 6 candidates main debug: no stream_filter modules matched main debug: looking for stream_filter module matching "stream_filter_record": 6 candidates main debug: no stream_filter modules matched main debug: creating demux: access='file' demux='' location='/C:/test.sdp' file='C:\test.sdp' main debug: looking for demux module matching "any": 63 candidates live555 debug: version 2012.12.18 live555 debug: RTP subsession 'video/MP4V-ES' main debug: selecting program id=0 live555 debug: setup start: 0.000000 stop:0.000000 live555 debug: play start: 0.000000 stop:0.000000 main debug: using demux module "live555" main debug: looking for a subtitle file in C:\ main debug: looking for decoder module matching "any": 39 candidates avcodec debug: trying to use direct rendering avcodec debug: allowing 1 thread(s) for decoding avcodec debug: avcodec codec (MPEG-4 Video) started main debug: using decoder module "avcodec" main debug: looking for meta reader module matching "any": 2 candidates lua debug: Trying Lua scripts in C:\Users\Admin\AppData\Roaming\vlc\lua\meta\reader lua debug: Trying Lua scripts in C:\Users\Admin\Desktop\vlc-2.2.0-git-20130707-0003\lua\meta\reader lua debug: Trying Lua playlist script C:\Users\Admin\Desktop\vlc-2.2.0-git-20130707-0003\lua\meta\reader\filename.luac main debug: no meta reader modules matched main debug: `file:///C:/test.sdp' successfully opened main debug: no fetch required for (null) (art currently (null)) qt4 debug: IM: Setting an input main debug: Buffering 0% main debug: Buffering 4% main debug: Buffering 8% main debug: Buffering 12% main debug: Buffering 16% main debug: Buffering 20% main debug: Buffering 24% main debug: Buffering 28% main debug: Buffering 32% main debug: Buffering 36% main debug: Buffering 40% main debug: Buffering 44% main debug: Buffering 48% main debug: Buffering 52% main debug: Buffering 56% main debug: Buffering 60% main debug: Buffering 64% main debug: Buffering 68% main debug: Buffering 72% main debug: Buffering 76% main debug: Buffering 80% main debug: Buffering 84% main debug: Buffering 88% main debug: Buffering 92% live555 debug: tk->rtpSource->hasBeenSynchronizedUsingRTCP() main error: ES_OUT_RESET_PCR called

At this point no video is streaming, although wireshark shows incoming video data to the machine in response to the SDP being loaded