Streaming over DCCP CCID3 using 0.9.8a

[sblee]

Hi,

I'm currently interested in streaming over DCCP CCID3 since it adopts TCP Friendly Rate Control (TFRC).

Using my own build from vlc 0.9.8a source code on Ubuntu 8.10, I succeded with streaming using UDP and DCCP CCID2.

For DCCP tests for CCID2 and CCID3, I used same commands as below.

vlc -vvv ../test.mpg --sout '#rtp{dst=127.0.0.1,port=1234,mux=ts,proto=dccp}'

vlc -vvv dccp://127.0.0.1:1234

In order to change dccp ccids, I used commands as below. In order to use dccp ccid 3, below commands should include 3 instead of 2 actually.

sudo modprobe dccp
sudo modprobe dccp_ccid2
sudo sysctl -w net.dccp.default.seq_window=10000
sudo sysctl -w net.dccp.default.rx_ccid=2
sudo sysctl -w net.dccp.default.tx_ccid=2

When dccp ccid 2 is used, the receiver plays mpeg video and audio properly. When dccp ccid 3 is applied, receiver does not provide video and audio at all.

I worried about the operation in dccp socket but it looks ok when I test with ttcp variant for dccp which is availabe from http://www.linuxfoundation.org/en/Net:DCCP_Testing. Current linux kernel version I'm using is 2.6.27-11.

Thank you in advance for your kind responses.

S.B.

P.S. I also attached log files of ccid 3 test for receiver and streamer.

Receiver log with CCID3
VLC media player 0.9.8a Grishenko
[00000001] main libvlc debug: VLC media player - version 0.9.8a Grishenko - (c) 1996-2008 the VideoLAN team
[00000001] main libvlc debug: libvlc was configured with ./configure '--prefix=/usr'
[00000001] main libvlc debug: translation test: code is "C"
[00000001] main libvlc debug: checking builtin modules
[00000001] main libvlc debug: checking plugin modules
[00000001] main libvlc debug: loading plugins cache file /home/sblee/.cache/vlc/plugins-04041e.dat
[00000001] main libvlc debug: recursively browsing `/usr/lib/vlc'
[00000001] main libvlc debug: recursively browsing `./modules'
[00000001] main libvlc debug: module bank initialized, found 263 modules
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU
[00000001] main libvlc debug: looking for memcpy module: 3 candidates
[00000001] main libvlc debug: using memcpy module "memcpymmxext"
[00000356] main interaction debug: thread started
[00000356] main interaction debug: thread 3082222480 (Interaction control) created at priority 0 (interface/interaction.c:382)
[00000358] main input debug: Creating an input for 'Media Library'
[00000358] main input debug: Input is a meta file: disabling unneeded options
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/sblee/.local/share/vlc/ml.xspf'
[00000358] main input debug: creating access 'file' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: looking for access module: 3 candidates
[00000359] access_file access debug: opening file `/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: using access module "access_file"
[00000359] main access debug: TIMER module_Need() : 8.741 ms - Total 8.741 ms / 1 intvls (Avg 8.741 ms)
[00000364] main stream debug: Using AStream*Stream
[00000364] main stream debug: pre-buffering...
[00000364] main stream debug: received first data for our buffer
[00000358] main input debug: creating demux: access='file' demux='xspf-open' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000365] main demux debug: looking for demux module: 1 candidate
[00000365] playlist demux debug: using XSPF playlist reader
[00000365] main demux debug: using demux module "playlist"
[00000365] main demux debug: TIMER module_Need() : 3.688 ms - Total 3.688 ms / 1 intvls (Avg 3.688 ms)
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' successfully opened
[00000380] main xml debug: looking for xml module: 2 candidates
[00000380] main xml debug: using xml module "xml"
[00000380] main xml debug: TIMER module_Need() : 5.992 ms - Total 5.992 ms / 1 intvls (Avg 5.992 ms)
[00000365] playlist demux debug: parsed 0 tracks successfully
[00000380] main xml debug: removing module "xml"
[00000358] main input debug: EOF reached
[00000358] main input debug: control type=1
[00000365] main demux debug: removing module "playlist"
[00000359] main access debug: removing module "access_file"
[00000358] main input debug: TIMER input launching for 'Media Library' : 42.321 ms - Total 42.321 ms / 1 intvls (Avg 42.321 ms)
[00000382] main preparser debug: thread started
[00000382] main preparser debug: waiting for thread initialization
[00000382] main preparser debug: thread 3073829776 (preparser) created at priority 0 (playlist/thread.c:79)
[00000383] main fetcher debug: thread started
[00000383] main fetcher debug: waiting for thread initialization
[00000383] main fetcher debug: thread 3059678096 (fetcher) created at priority 0 (playlist/thread.c:108)
[00000357] main playlist debug: thread started
[00000357] main playlist debug: waiting for thread initialization
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 0 items, index -1
[00000357] main playlist debug: thread 3051285392 (playlist) created at priority 0 (playlist/thread.c:117)
[00000384] main interface debug: looking for interface module: 1 candidate
[00000384] main interface debug: using interface module "hotkeys"
[00000384] main interface debug: TIMER module_Need() : 2.292 ms - Total 2.292 ms / 1 intvls (Avg 2.292 ms)
[00000384] main interface debug: thread started
[00000384] main interface debug: thread 3042892688 (interface) created at priority 0 (interface/interface.c:168)
[00000386] main interface debug: looking for interface module: 1 candidate
[00000386] main interface debug: using interface module "inhibit"
[00000386] main interface debug: TIMER module_Need() : 20.761 ms - Total 20.761 ms / 1 intvls (Avg 20.761 ms)
[00000386] main interface debug: thread started
[00000386] main interface debug: thread 3034499984 (interface) created at priority 0 (interface/interface.c:168)
[00000388] main interface debug: looking for interface module: 1 candidate
[00000388] main interface debug: using interface module "screensaver"
[00000388] main interface debug: TIMER module_Need() : 7.951 ms - Total 7.951 ms / 1 intvls (Avg 7.951 ms)
[00000388] main interface debug: thread started
[00000388] main interface debug: thread 3026107280 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: adding item `127.0.0.1:1234' ( dccp://127.0.0.1:1234 )
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 1 items, index -1
[00000390] main interface debug: looking for interface module: 20 candidates
[00000390] main interface debug: using interface module "signals"
[00000390] main interface debug: TIMER module_Need() : 9.472 ms - Total 9.472 ms / 1 intvls (Avg 9.472 ms)
[00000390] main interface debug: thread started
[00000390] main interface debug: thread 3009321872 (interface) created at priority 0 (interface/interface.c:168)
[00000001] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[00000392] main interface debug: looking for interface module: 3 candidates
[00000392] main interface debug: using interface module "qt4"
[00000392] main interface debug: TIMER module_Need() : 258.233 ms - Total 258.233 ms / 1 intvls (Avg 258.233 ms)
[00000392] main interface debug: thread started
[00000392] main interface debug: thread 2986748816 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: starting new item
[00000357] main playlist debug: processing request item null node Playlist skip 0
[00000357] main playlist debug: creating new input thread
[00000395] main input debug: Creating an input for '127.0.0.1:1234'
[00000395] main input debug: thread started
[00000395] main input debug: waiting for thread initialization
[00000395] main input debug: `dccp://127.0.0.1:1234' gives access `dccp' demux `' path `127.0.0.1:1234'
[00000395] main input debug: creating demux: access='dccp' demux='' path='127.0.0.1:1234'
[00000396] main demux debug: looking for access_demux module: 1 candidate
[00000396] main demux debug: net: connecting to 127.0.0.1 port 1234
[00000396] main demux debug: connection: Operation now in progress
[00000396] main demux debug: connection succeeded (socket = 12)
[00000395] main input debug: thread 2978356112 (input) created at priority 10 (input/input.c:370)
[00000396] main demux debug: using access_demux module "rtp"
[00000396] main demux debug: TIMER module_Need() : 29.440 ms - Total 29.440 ms / 1 intvls (Avg 29.440 ms)
[00000395] main input debug: `dccp://127.0.0.1:1234' successfully opened
[00000396] rtp demux debug: detected MPEG2 TS
[00000396] rtp demux debug: added payload type 33 (f = 90000 Hz)
[00000398] main stream debug: thread started
[00000398] main stream debug: creating demux: access='' demux='ts' path=''
[00000398] main stream debug: thread 2969455504 (stream out) created at priority 10 (input/demux.c:358)
[00000396] rtp demux debug: added RTP source (750b000e)
[00000395] main input debug: control type=1
[00000399] main demux debug: looking for demux module: 1 candidate
[00000399] main demux debug: using demux module "ts"
[00000399] main demux debug: TIMER module_Need() : 5.663 ms - Total 5.663 ms / 1 intvls (Avg 5.663 ms)
[00000399] ts demux debug: pid[68] unknown
[00000395] main input debug: EOF reached
[00000357] main playlist debug: finished input
[00000396] main demux debug: waitpipe: object killed
[00000357] main playlist debug: dying input
[00000396] rtp demux debug: removing RTP source (750b000e)
[00000399] ts demux debug: eof ?
[00000398] main stream debug: thread ended
[00000398] main stream debug: thread 2969455504 joined (input/demux.c:385)
[00000399] ts demux debug: pid list:
[00000399] ts demux debug: - pid[68] seen
[00000399] ts demux debug: - pid[8191] seen
[00000399] main demux debug: removing module "ts"
[00000396] main demux debug: removing module "rtp"
[00000395] main input debug: thread ended
[00000357] main playlist debug: dead input
[00000395] main input debug: thread 2978356112 joined (playlist/engine.c:244)
[00000395] main input debug: TIMER input launching for '127.0.0.1:1234' : 41.444 ms - Total 41.444 ms / 1 intvls (Avg 41.444 ms)
[00000357] main playlist debug: starting new item
[00000357] main playlist debug: changing item without a request (current 0/1)
[00000357] main playlist debug: nothing to play
[00000392] qt4 interface debug: Error while initializing qt-specific localization
[00000001] main libvlc debug: removing all interfaces
[00000392] qt4 interface debug: Quitting the Qt4 Interface
[00000392] qt4 interface debug: Destroying the main interface
[00000392] qt4 interface debug: Destroying the Dialog Provider
[00000392] main interface debug: thread ended
[00000392] main interface debug: thread 2986748816 joined (interface/interface.c:188)
[00000392] main interface debug: removing module "qt4"
[00000390] main interface debug: thread ended
[00000390] main interface debug: thread 3009321872 joined (interface/interface.c:188)
[00000390] main interface debug: removing module "signals"
[00000388] main interface debug: thread ended
[00000388] main interface debug: thread 3026107280 joined (interface/interface.c:188)
[00000388] main interface debug: removing module "screensaver"
[00000386] main interface debug: thread ended
[00000386] main interface debug: thread 3034499984 joined (interface/interface.c:188)
[00000386] main interface debug: removing module "inhibit"
[00000384] main interface debug: thread ended
[00000384] main interface debug: thread 3042892688 joined (interface/interface.c:188)
[00000384] main interface debug: removing module "hotkeys"
[00000001] main libvlc debug: removing all services discovery tasks
[00000001] main libvlc debug: removing playlist
[00000357] main playlist debug: saving Media Library to file /home/sblee/.local/share/vlc/ml.xspf
[00000357] main playlist debug: looking for playlist export module: 1 candidate
[00000357] main playlist debug: using playlist export module "export"
[00000357] main playlist debug: TIMER module_Need() : 1.343 ms - Total 1.343 ms / 1 intvls (Avg 1.343 ms)
[00000357] main playlist debug: removing module "export"
[00000382] main preparser debug: thread ended
[00000382] main preparser debug: thread 3073829776 joined (playlist/engine.c:521)
[00000383] main fetcher debug: thread ended
[00000383] main fetcher debug: thread 3059678096 joined (playlist/engine.c:523)
[00000357] main playlist debug: thread ended
[00000357] main playlist debug: thread 3051285392 joined (libvlc.c:993)
[00000382] main preparser debug: Destroyed
[00000383] main fetcher debug: Destroyed
[00000357] main playlist debug: Destroyed
[00000001] main libvlc debug: removing interaction
[00000356] main interaction debug: thread ended
[00000356] main interaction debug: thread 3082222480 joined (interface/interaction.c:400)
[00000001] main libvlc debug: removing all video outputs
[00000001] main libvlc debug: TIMER ML Load : Total 50.363 ms / 1 intvls (Avg 50.363 ms)
[00000001] main libvlc debug: TIMER Items array build : Total 1.414 ms / 2 intvls (Avg 0.707 ms)
[00000001] main libvlc debug: TIMER ML Dump : Total 2.008 ms / 1 intvls (Avg 2.008 ms)
[00000001] main libvlc debug: removing stats
[00000001] main libvlc debug: removing module "memcpymmxext"
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: writing plugins cache /home/sblee/.cache/vlc/plugins-04041e.dat

Streamer log with CCID3
VLC media player 0.9.8a Grishenko
[00000001] main libvlc debug: VLC media player - version 0.9.8a Grishenko - (c) 1996-2008 the VideoLAN team
[00000001] main libvlc debug: libvlc was configured with ./configure '--prefix=/usr'
[00000001] main libvlc debug: translation test: code is "C"
[00000001] main libvlc debug: checking builtin modules
[00000001] main libvlc debug: checking plugin modules
[00000001] main libvlc debug: loading plugins cache file /home/sblee/.cache/vlc/plugins-04041e.dat
[00000001] main libvlc debug: recursively browsing `/usr/lib/vlc'
[00000001] main libvlc debug: recursively browsing `./modules'
[00000001] main libvlc debug: module bank initialized, found 263 modules
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU
[00000001] main libvlc debug: looking for memcpy module: 3 candidates
[00000001] main libvlc debug: using memcpy module "memcpymmxext"
[00000356] main interaction debug: thread started
[00000356] main interaction debug: thread 3081911184 (Interaction control) created at priority 0 (interface/interaction.c:382)
[00000358] main input debug: Creating an input for 'Media Library'
[00000358] main input debug: Input is a meta file: disabling unneeded options
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/sblee/.local/share/vlc/ml.xspf'
[00000358] main input debug: creating access 'file' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: looking for access module: 3 candidates
[00000359] access_file access debug: opening file `/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: using access module "access_file"
[00000359] main access debug: TIMER module_Need() : 8.184 ms - Total 8.184 ms / 1 intvls (Avg 8.184 ms)
[00000364] main stream debug: Using AStream*Stream
[00000364] main stream debug: pre-buffering...
[00000364] main stream debug: received first data for our buffer
[00000358] main input debug: creating demux: access='file' demux='xspf-open' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000365] main demux debug: looking for demux module: 1 candidate
[00000365] playlist demux debug: using XSPF playlist reader
[00000365] main demux debug: using demux module "playlist"
[00000365] main demux debug: TIMER module_Need() : 5.236 ms - Total 5.236 ms / 1 intvls (Avg 5.236 ms)
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' successfully opened
[00000380] main xml debug: looking for xml module: 2 candidates
[00000380] main xml debug: using xml module "xml"
[00000380] main xml debug: TIMER module_Need() : 5.642 ms - Total 5.642 ms / 1 intvls (Avg 5.642 ms)
[00000365] playlist demux debug: parsed 0 tracks successfully
[00000380] main xml debug: removing module "xml"
[00000358] main input debug: EOF reached
[00000358] main input debug: control type=1
[00000365] main demux debug: removing module "playlist"
[00000359] main access debug: removing module "access_file"
[00000358] main input debug: TIMER input launching for 'Media Library' : 38.226 ms - Total 38.226 ms / 1 intvls (Avg 38.226 ms)
[00000382] main preparser debug: thread started
[00000382] main preparser debug: waiting for thread initialization
[00000382] main preparser debug: thread 3073518480 (preparser) created at priority 0 (playlist/thread.c:79)
[00000383] main fetcher debug: thread started
[00000383] main fetcher debug: waiting for thread initialization
[00000383] main fetcher debug: thread 3059366800 (fetcher) created at priority 0 (playlist/thread.c:108)
[00000357] main playlist debug: thread started
[00000357] main playlist debug: waiting for thread initialization
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 0 items, index -1
[00000357] main playlist debug: thread 3050974096 (playlist) created at priority 0 (playlist/thread.c:117)
[00000384] main interface debug: looking for interface module: 1 candidate
[00000384] main interface debug: using interface module "hotkeys"
[00000384] main interface debug: TIMER module_Need() : 3.158 ms - Total 3.158 ms / 1 intvls (Avg 3.158 ms)
[00000384] main interface debug: thread started
[00000384] main interface debug: thread 3042581392 (interface) created at priority 0 (interface/interface.c:168)
[00000386] main interface debug: looking for interface module: 1 candidate
[00000386] main interface debug: using interface module "inhibit"
[00000386] main interface debug: TIMER module_Need() : 57.592 ms - Total 57.592 ms / 1 intvls (Avg 57.592 ms)
[00000386] main interface debug: thread started
[00000386] main interface debug: thread 3034188688 (interface) created at priority 0 (interface/interface.c:168)
[00000388] main interface debug: looking for interface module: 1 candidate
[00000388] main interface debug: using interface module "screensaver"
[00000388] main interface debug: TIMER module_Need() : 1.796 ms - Total 1.796 ms / 1 intvls (Avg 1.796 ms)
[00000388] main interface debug: thread started
[00000388] main interface debug: thread 3025795984 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: adding item `JurassicPark3_3_0M.m2p' ( ../JurassicPark3_3_0M.m2p )
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 1 items, index -1
[00000390] main interface debug: looking for interface module: 20 candidates
[00000390] main interface debug: using interface module "signals"
[00000390] main interface debug: TIMER module_Need() : 5.279 ms - Total 5.279 ms / 1 intvls (Avg 5.279 ms)
[00000390] main interface debug: thread started
[00000390] main interface debug: thread 3009010576 (interface) created at priority 0 (interface/interface.c:168)
[00000001] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[00000392] main interface debug: looking for interface module: 3 candidates
[00000392] main interface debug: using interface module "qt4"
[00000392] main interface debug: TIMER module_Need() : 220.509 ms - Total 220.509 ms / 1 intvls (Avg 220.509 ms)
[00000392] main interface debug: thread started
[00000392] main interface debug: thread 2985376656 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: starting new item
[00000357] main playlist debug: processing request item null node Playlist skip 0
[00000357] main playlist debug: creating new input thread
[00000395] main input debug: Creating an input for 'JurassicPark3_3_0M.m2p'
[00000395] main input debug: thread started
[00000395] main input debug: waiting for thread initialization
[00000396] main stream output debug: stream=`rtp'
[00000397] main stream out debug: looking for sout stream module: 1 candidate
[00000395] main input debug: thread 2976983952 (input) created at priority 10 (input/input.c:370)
[00000397] main stream out debug: set config option: sout-rtp-dst to 127.0.0.1
[00000397] main stream out debug: set config option: sout-rtp-port to 1234
[00000397] main stream out debug: set config option: sout-rtp-proto to dccp
[00000397] main stream out debug: set config option: sout-rtp-mux to ts
[00000400] main mux debug: looking for sout mux module: 1 candidate
[00000400] mux_ts mux debug: shaping=200000 pcr=70000 dts_delay=400000
[00000400] main mux debug: using sout mux module "mux_ts"
[00000400] main mux debug: TIMER module_Need() : 143.910 ms - Total 143.910 ms / 1 intvls (Avg 143.910 ms)
[00000396] main stream output debug: muxer support adding stream at any time
[00000396] main stream output debug: muxer prefers to wait for all ES before starting to mux
[00000397] stream_out_rtp stream out debug: maximum RTP packet size: 1400 bytes
[00000397] main stream out debug: net: listening to 127.0.0.1 port 1234
[00000402] main generic debug: thread started
[00000402] main generic debug: thread 2967514000 (RTP send thread) created at priority 20 (rtp.c:1235)
[00000397] stream_out_rtp stream out debug: sdp=
v=0
o=- 14796460313589228491 14796460313589228491 IN IP4 shuttlex
s=Unnamed
i=N/A
c=IN IP4 127.0.0.1
t=0 0
a=tool:vlc 0.9.8a
a=recvonly
a=type:broadcast
a=charset:UTF-8
a=rtcp-mux
m=video 1234 DCCP/RTP/AVP 33
b=RR:0
a=rtpmap:33 MP2T/90000
a=setup:passive
a=dccp-service-code:SC:RTPV

[00000397] main stream out debug: using sout stream module "stream_out_rtp"
[00000397] main stream out debug: TIMER module_Need() : 598.991 ms - Total 598.991 ms / 1 intvls (Avg 598.991 ms)
[00000395] main input debug: `../JurassicPark3_3_0M.m2p' gives access `' demux `' path `../JurassicPark3_3_0M.m2p'
[00000395] main input debug: creating demux: access='' demux='' path='../JurassicPark3_3_0M.m2p'
[00000403] main demux debug: looking for access_demux module: 2 candidates
[00000403] main demux debug: TIMER module_Need() : 3.798 ms - Total 3.798 ms / 1 intvls (Avg 3.798 ms)
[00000395] main input debug: creating access '' path='../JurassicPark3_3_0M.m2p'
[00000407] main access debug: looking for access module: 5 candidates
[00000407] vcd access debug: trying .cue file: ../JurassicPark3_3_0M.cue
[00000407] vcd access debug: could not find .cue file
[00000407] access_file access debug: opening file `../JurassicPark3_3_0M.m2p'
[00000407] main access debug: using access module "access_file"
[00000407] main access debug: TIMER module_Need() : 2.373 ms - Total 2.373 ms / 1 intvls (Avg 2.373 ms)
[00000409] main stream debug: Using AStream*Stream
[00000409] main stream debug: pre-buffering...
[00000409] main stream debug: received first data for our buffer
[00000409] main stream debug: pre-buffering done 1408981 bytes in 0s - 37515 kbytes/s
[00000395] main input debug: creating demux: access='' demux='' path='../JurassicPark3_3_0M.m2p'
[00000410] main demux debug: looking for demux module: 52 candidates
[00000410] main demux debug: using demux module "ps"
[00000410] main demux debug: TIMER module_Need() : 151.835 ms - Total 151.835 ms / 1 intvls (Avg 151.835 ms)
[00000395] main input debug: looking for a subtitle file in ../
[00000395] main input debug: starting in async mode
[00000395] main input debug: `../JurassicPark3_3_0M.m2p' successfully opened
[00000410] ps demux warning: garbage at input, trying to resync...
[00000410] ps demux warning: found sync code
[00000410] ps demux debug: we found a length of: 900556033
[00000395] main input debug: control type=1
[00000395] main input debug: selecting program id=0
[00000443] main packetizer debug: looking for packetizer module: 17 candidates
[00000443] main packetizer debug: using packetizer module "mpeg_audio"
[00000443] main packetizer debug: TIMER module_Need() : 87.964 ms - Total 87.964 ms / 1 intvls (Avg 87.964 ms)
[00000395] main input debug: stream out mode -> no decoder thread
[00000472] main packetizer debug: looking for packetizer module: 17 candidates
[00000472] main packetizer debug: using packetizer module "packetizer_mpegvideo"
[00000472] main packetizer debug: TIMER module_Need() : 1.139 ms - Total 1.139 ms / 1 intvls (Avg 1.139 ms)
[00000395] main input debug: stream out mode -> no decoder thread
[00000472] packetizer_mpegvideo packetizer debug: size 352x288 fps=25.000
[00000396] main stream output debug: adding a new sout input (sout_input:0xa12e588)
[00000400] main mux debug: adding a new input
[00000400] mux_ts mux debug: adding input codec=mpgv pid=68
[00000400] mux_ts mux debug: new PCR PID is 68
[00000472] main packetizer debug: switching to sync mode
[00000443] mpeg_audio packetizer debug: MPGA channels:2 samplerate:44100 bitrate:128
[00000396] main stream output debug: adding a new sout input (sout_input:0xa12b7f0)
[00000400] main mux debug: adding a new input
[00000400] mux_ts mux debug: adding input codec=mpga pid=69
[00000392] qt4 interface debug: Error while initializing qt-specific localization
[00000392] qt4 interface debug: Updating the stream status: 3
[00000402] main generic debug: accepted socket 22 (from socket 11)
[00000402] stream_out_rtp generic debug: adding socket 22
[00000402] stream_out_rtp generic debug: removing socket 22
[00000357] main playlist debug: incoming request - stopping current input
[00000357] main playlist debug: dying input
[00000001] main libvlc debug: removing all interfaces
[00000392] qt4 interface debug: Quitting the Qt4 Interface
[00000392] qt4 interface debug: Destroying the main interface
[00000392] qt4 interface debug: Destroying the Dialog Provider
[00000395] main input debug: control type=0
[00000395] main input debug: control: stopping input
[00000443] main packetizer debug: removing module "mpeg_audio"
[00000443] main packetizer debug: killing decoder fourcc `mpga', 0 PES in FIFO
[00000396] main stream output debug: removing a sout input (sout_input:0xa12b7f0)
[00000400] mux_ts mux debug: removing input pid=69
[00000472] main packetizer debug: removing module "packetizer_mpegvideo"
[00000472] main packetizer debug: killing decoder fourcc `mpgv', 0 PES in FIFO
[00000396] main stream output debug: removing a sout input (sout_input:0xa12e588)
[00000400] mux_ts mux debug: removing input pid=68
[00000400] mux_ts mux debug: new PCR PID is 8191
[00000400] main mux warning: no more input streams for this mux
[00000395] main input debug: Program doesn't contain anymore ES
[00000410] main demux debug: removing module "ps"
[00000407] main access debug: removing module "access_file"
[00000395] main input debug: thread ended
[00000392] main interface debug: thread ended
[00000392] main interface debug: thread 2985376656 joined (interface/interface.c:188)
[00000392] main interface debug: removing module "qt4"
[00000390] main interface debug: thread ended
[00000390] main interface debug: thread 3009010576 joined (interface/interface.c:188)
[00000390] main interface debug: removing module "signals"
[00000388] main interface debug: thread ended
[00000388] main interface debug: thread 3025795984 joined (interface/interface.c:188)
[00000388] main interface debug: removing module "screensaver"
[00000386] main interface debug: thread ended
[00000386] main interface debug: thread 3034188688 joined (interface/interface.c:188)
[00000386] main interface debug: removing module "inhibit"
[00000384] main interface debug: thread ended
[00000384] main interface debug: thread 3042581392 joined (interface/interface.c:188)
[00000384] main interface debug: removing module "hotkeys"
[00000001] main libvlc debug: removing all services discovery tasks
[00000001] main libvlc debug: removing playlist
[00000357] main playlist debug: dead input
[00000395] main input debug: thread 2976983952 joined (playlist/engine.c:244)
[00000395] main input debug: TIMER input launching for 'JurassicPark3_3_0M.m2p' : 829.312 ms - Total 829.312 ms / 1 intvls (Avg 829.312 ms)
[00000397] main stream out debug: destroying chain... (name=rtp)
[00000402] main generic debug: waitpipe: object killed
[00000402] main generic debug: thread ended
[00000402] main generic debug: thread 2967514000 joined (rtp.c:1271)
[00000400] main mux debug: removing module "mux_ts"
[00000397] main stream out debug: removing module "stream_out_rtp"
[00000397] main stream out debug: destroying chain done
[00000357] main playlist debug: saving Media Library to file /home/sblee/.local/share/vlc/ml.xspf
[00000357] main playlist debug: looking for playlist export module: 1 candidate
[00000357] main playlist debug: using playlist export module "export"
[00000357] main playlist debug: TIMER module_Need() : 1.420 ms - Total 1.420 ms / 1 intvls (Avg 1.420 ms)
[00000357] main playlist debug: removing module "export"
[00000382] main preparser debug: thread ended
[00000382] main preparser debug: thread 3073518480 joined (playlist/engine.c:521)
[00000383] main fetcher debug: thread ended
[00000383] main fetcher debug: thread 3059366800 joined (playlist/engine.c:523)
[00000357] main playlist debug: thread ended
[00000357] main playlist debug: thread 3050974096 joined (libvlc.c:993)
[00000382] main preparser debug: Destroyed
[00000383] main fetcher debug: Destroyed
[00000357] main playlist debug: Destroyed
[00000001] main libvlc debug: removing interaction
[00000356] main interaction debug: thread ended
[00000356] main interaction debug: thread 3081911184 joined (interface/interaction.c:400)
[00000001] main libvlc debug: removing all video outputs
[00000001] main libvlc debug: TIMER ML Load : Total 46.091 ms / 1 intvls (Avg 46.091 ms)
[00000001] main libvlc debug: TIMER Items array build : Total 0.434 ms / 2 intvls (Avg 0.217 ms)
[00000001] main libvlc debug: TIMER ML Dump : Total 1.974 ms / 1 intvls (Avg 1.974 ms)
[00000001] main libvlc debug: removing stats
[00000001] main libvlc debug: removing module "memcpymmxext"
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: writing plugins cache /home/sblee/.cache/vlc/plugins-04041e.dat

[Rémi Denis-Courmont]

VLC is sending RTP packets all the same whether it's UDP, UDP-Lite or DCCP, and regardless of the congestion control algorithm in the later case.

Your logs (unsurprisingly) show that the receiver does sees at least one packet, but then it's instructed to shut down.

[sblee]

Thanks for reply.

As you mentioned, it need to work whether any transport protocol is used for RTP. I had same test using DCCP CCID 2 and UDP and those are working fine. Maybe, I'm missing something. Can you indicate what could be differences betwen CCID2 and CCID3 test if I attach CCID2 logs?

Receiver log with CCID2
VLC media player 0.9.8a Grishenko
[00000001] main libvlc debug: VLC media player - version 0.9.8a Grishenko - (c) 1996-2008 the VideoLAN team
[00000001] main libvlc debug: libvlc was configured with ./configure '--prefix=/usr'
[00000001] main libvlc debug: translation test: code is "C"
[00000001] main libvlc debug: checking builtin modules
[00000001] main libvlc debug: checking plugin modules
[00000001] main libvlc debug: loading plugins cache file /home/sblee/.cache/vlc/plugins-04041e.dat
[00000001] main libvlc debug: recursively browsing `/usr/lib/vlc'
[00000001] main libvlc debug: recursively browsing `./modules'
[00000001] main libvlc debug: module bank initialized, found 263 modules
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU
[00000001] main libvlc debug: looking for memcpy module: 3 candidates
[00000001] main libvlc debug: using memcpy module "memcpymmxext"
[00000356] main interaction debug: thread started
[00000356] main interaction debug: thread 3081702288 (Interaction control) created at priority 0 (interface/interaction.c:382)
[00000358] main input debug: Creating an input for 'Media Library'
[00000358] main input debug: Input is a meta file: disabling unneeded options
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/sblee/.local/share/vlc/ml.xspf'
[00000358] main input debug: creating access 'file' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: looking for access module: 3 candidates
[00000359] access_file access debug: opening file `/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: using access module "access_file"
[00000359] main access debug: TIMER module_Need() : 7.415 ms - Total 7.415 ms / 1 intvls (Avg 7.415 ms)
[00000364] main stream debug: Using AStream*Stream
[00000364] main stream debug: pre-buffering...
[00000364] main stream debug: received first data for our buffer
[00000358] main input debug: creating demux: access='file' demux='xspf-open' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000365] main demux debug: looking for demux module: 1 candidate
[00000365] playlist demux debug: using XSPF playlist reader
[00000365] main demux debug: using demux module "playlist"
[00000365] main demux debug: TIMER module_Need() : 5.678 ms - Total 5.678 ms / 1 intvls (Avg 5.678 ms)
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' successfully opened
[00000380] main xml debug: looking for xml module: 2 candidates
[00000380] main xml debug: using xml module "xml"
[00000380] main xml debug: TIMER module_Need() : 6.890 ms - Total 6.890 ms / 1 intvls (Avg 6.890 ms)
[00000365] playlist demux debug: parsed 0 tracks successfully
[00000380] main xml debug: removing module "xml"
[00000358] main input debug: EOF reached
[00000358] main input debug: control type=1
[00000365] main demux debug: removing module "playlist"
[00000359] main access debug: removing module "access_file"
[00000358] main input debug: TIMER input launching for 'Media Library' : 55.181 ms - Total 55.181 ms / 1 intvls (Avg 55.181 ms)
[00000382] main preparser debug: thread started
[00000382] main preparser debug: waiting for thread initialization
[00000382] main preparser debug: thread 3073309584 (preparser) created at priority 0 (playlist/thread.c:79)
[00000383] main fetcher debug: thread started
[00000383] main fetcher debug: waiting for thread initialization
[00000383] main fetcher debug: thread 3059157904 (fetcher) created at priority 0 (playlist/thread.c:108)
[00000357] main playlist debug: thread started
[00000357] main playlist debug: waiting for thread initialization
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 0 items, index -1
[00000357] main playlist debug: thread 3050765200 (playlist) created at priority 0 (playlist/thread.c:117)
[00000384] main interface debug: looking for interface module: 1 candidate
[00000384] main interface debug: using interface module "hotkeys"
[00000384] main interface debug: TIMER module_Need() : 5.614 ms - Total 5.614 ms / 1 intvls (Avg 5.614 ms)
[00000384] main interface debug: thread started
[00000384] main interface debug: thread 3042372496 (interface) created at priority 0 (interface/interface.c:168)
[00000386] main interface debug: looking for interface module: 1 candidate
[00000386] main interface debug: using interface module "inhibit"
[00000386] main interface debug: TIMER module_Need() : 25.598 ms - Total 25.598 ms / 1 intvls (Avg 25.598 ms)
[00000386] main interface debug: thread started
[00000386] main interface debug: thread 3033979792 (interface) created at priority 0 (interface/interface.c:168)
[00000388] main interface debug: looking for interface module: 1 candidate
[00000388] main interface debug: using interface module "screensaver"
[00000388] main interface debug: TIMER module_Need() : 2.970 ms - Total 2.970 ms / 1 intvls (Avg 2.970 ms)
[00000388] main interface debug: thread started
[00000388] main interface debug: thread 3025587088 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: adding item `127.0.0.1:1234' ( dccp://127.0.0.1:1234 )
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 1 items, index -1
[00000390] main interface debug: looking for interface module: 20 candidates
[00000390] main interface debug: using interface module "signals"
[00000390] main interface debug: TIMER module_Need() : 4.882 ms - Total 4.882 ms / 1 intvls (Avg 4.882 ms)
[00000390] main interface debug: thread started
[00000390] main interface debug: thread 3008801680 (interface) created at priority 0 (interface/interface.c:168)
[00000001] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[00000392] main interface debug: looking for interface module: 3 candidates
[00000392] main interface debug: using interface module "qt4"
[00000392] main interface debug: TIMER module_Need() : 258.854 ms - Total 258.854 ms / 1 intvls (Avg 258.854 ms)
[00000392] main interface debug: thread started
[00000392] main interface debug: thread 2986228624 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: starting new item
[00000357] main playlist debug: processing request item null node Playlist skip 0
[00000357] main playlist debug: creating new input thread
[00000395] main input debug: Creating an input for '127.0.0.1:1234'
[00000395] main input debug: thread started
[00000395] main input debug: waiting for thread initialization
[00000395] main input debug: `dccp://127.0.0.1:1234' gives access `dccp' demux `' path `127.0.0.1:1234'
[00000395] main input debug: creating demux: access='dccp' demux='' path='127.0.0.1:1234'
[00000396] main demux debug: looking for access_demux module: 1 candidate
[00000396] main demux debug: net: connecting to 127.0.0.1 port 1234
[00000396] main demux debug: connection: Operation now in progress
[00000396] main demux debug: connection succeeded (socket = 14)
[00000395] main input debug: thread 2977835920 (input) created at priority 10 (input/input.c:370)
[00000396] main demux debug: using access_demux module "rtp"
[00000396] main demux debug: TIMER module_Need() : 31.591 ms - Total 31.591 ms / 1 intvls (Avg 31.591 ms)
[00000395] main input debug: `dccp://127.0.0.1:1234' successfully opened
[00000396] rtp demux debug: detected MPEG2 TS
[00000396] rtp demux debug: added payload type 33 (f = 90000 Hz)
[00000398] main stream debug: thread started
[00000398] main stream debug: creating demux: access='' demux='ts' path=''
[00000398] main stream debug: thread 2968935312 (stream out) created at priority 10 (input/demux.c:358)
[00000396] rtp demux debug: added RTP source (b3ee53bc)
[00000395] main input debug: control type=1
[00000399] main demux debug: looking for demux module: 1 candidate
[00000399] main demux debug: using demux module "ts"
[00000399] main demux debug: TIMER module_Need() : 15.268 ms - Total 15.268 ms / 1 intvls (Avg 15.268 ms)
[00000399] ts demux debug: pid[68] unknown
[00000399] ts demux debug: pid[69] unknown
libdvbpsi error (PSI decoder): TS discontinuity (received 2, expected 0) for PID 0
[00000399] ts demux debug: PATCallBack called
[00000399] ts demux debug: new PAT ts_id=57381 version=26 current_next=1
[00000399] ts demux debug: * number=1 pid=66
libdvbpsi error (PSI decoder): TS discontinuity (received 2, expected 0) for PID 66
[00000399] ts demux debug: PMTCallBack called
[00000399] ts demux debug: new PMT program number=1 version=6 pid_pcr=68
[00000399] ts demux debug: * es pid=68 type=2 fcc=mpgv
[00000395] main input debug: selecting program id=1
[00000401] main decoder debug: looking for decoder module: 26 candidates
[00000401] main decoder debug: using decoder module "libmpeg2"
[00000401] main decoder debug: TIMER module_Need() : 62.442 ms - Total 62.442 ms / 1 intvls (Avg 62.442 ms)
[00000401] main decoder debug: thread started
[00000401] main decoder debug: thread 2957396880 (decoder) created at priority 0 (input/decoder.c:217)
[00000399] ts demux debug: * es pid=69 type=3 fcc=mpga
[00000407] main decoder debug: looking for decoder module: 26 candidates
[00000407] main decoder debug: using decoder module "mpeg_audio"
[00000407] main decoder debug: TIMER module_Need() : 48.545 ms - Total 48.545 ms / 1 intvls (Avg 48.545 ms)
[00000407] main decoder debug: thread started
[00000407] main decoder debug: thread 2947546000 (decoder) created at priority 5 (input/decoder.c:217)
[00000399] ts demux warning: first packet for pid=68 cc=0xe
[00000399] ts demux warning: first packet for pid=69 cc=0x8
[00000407] mpeg_audio decoder debug: waiting for PTS
[00000407] mpeg_audio decoder debug: MPGA channels:2 samplerate:44100 bitrate:128
[00000407] main decoder debug: no aout present, spawning one
[00000419] main audio output debug: looking for audio output module: 5 candidates
[00000419] alsa audio output debug: opening ALSA device `default'
[00000401] libmpeg2 decoder debug: 352x288 (display 352,288), aspect 576000, sar 12:11, 25.000 fps
[00000401] main decoder debug: no usable vout present, spawning one
[00000420] main video output debug: window size: 384x288
[00000420] main video output debug: looking for video output module: 6 candidates
[00000420] xvideo video output debug: adaptor 0, port 86, format 0x32315659 (YV12) planar
[00000419] main audio output debug: thread started
[00000419] main audio output debug: thread 2926230416 (aout) created at priority 15 (alsa.c:687)
[00000419] main audio output debug: using audio output module "alsa"
[00000419] main audio output debug: TIMER module_Need() : 341.689 ms - Total 341.689 ms / 1 intvls (Avg 341.689 ms)
[00000419] main audio output debug: output 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
[00000419] main audio output debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
[00000419] main audio output debug: no need for any filter
[00000419] main audio output debug: looking for audio mixer module: 3 candidates
[00000419] main audio output debug: using audio mixer module "float32_mixer"
[00000419] main audio output debug: TIMER module_Need() : 2.728 ms - Total 2.728 ms / 1 intvls (Avg 2.728 ms)
[00000419] main audio output debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1262 bytes
[00000419] main audio output debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo
[00000424] main audio output debug: looking for audio filter module: 24 candidates
[00000424] main audio output debug: using audio filter module "mpgatofixed32"
[00000427] main window debug: looking for vout window module: 1 candidate
[00000427] main window debug: TIMER module_Need() : 12.676 ms - Total 12.676 ms / 1 intvls (Avg 12.676 ms)
[00000427] main window debug: no window provider available
[00000424] main audio output debug: TIMER module_Need() : 19.936 ms - Total 19.936 ms / 1 intvls (Avg 19.936 ms)
[00000419] main audio output debug: found a filter for the whole conversion
[00000419] main audio output debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo
[00000431] main audio output debug: looking for audio filter module: 24 candidates
[00000431] main audio output debug: using audio filter module "bandlimited_resampler"
[00000431] main audio output debug: TIMER module_Need() : 19.411 ms - Total 19.411 ms / 1 intvls (Avg 19.411 ms)
[00000419] main audio output debug: found a filter for the whole conversion
[00000420] xvideo video output debug: XShm video extension v1.1 (without pixmaps, opcode: 145)
[00000420] xvideo video output debug: Window manager supports NetWM
[00000420] xvideo video output debug: Window manager supports _NET_WM_STATE_FULLSCREEN
[00000420] xvideo video output debug: Window manager supports _NET_WM_STATE_ABOVE
[00000420] xvideo video output debug: Window manager supports _NET_WM_STATE_BELOW
[00000420] main video output debug: using video output module "xvideo"
[00000420] main video output debug: TIMER module_Need() : 433.326 ms - Total 433.326 ms / 1 intvls (Avg 433.326 ms)
[00000420] main video output debug: thread started
[00000420] main video output debug: waiting for thread initialization
[00000420] main video output debug: got 8 direct buffer(s)
[00000420] main video output debug: picture in 352x288 (0,0,352x288), chroma I420, ar 4:3, sar 12:11
[00000420] main video output debug: picture user 352x288 (0,0,352x288), chroma I420, ar 4:3, sar 12:11
[00000420] main video output debug: picture out 352x288 (0,0,352x288), chroma I420, ar 4:3, sar 12:11
[00000420] main video output debug: direct render, mapping render pictures 0-6 to system pictures 1-7
[00000420] main video output debug: thread 2917632912 (video output) created at priority 15 (video_output/video_output.c:502)
[00000401] main decoder warning: dts != current_pts (-645298)
[00000401] main decoder warning: decoder synchro warning: pts != current_date (-40000)
[00000419] main audio output warning: output date isn't PTS date, requesting resampling (63219)
[00000419] main audio output warning: buffer is 65342 late, triggering upsampling
[00000439] main spu text debug: looking for text renderer module: 2 candidates
[00000441] main generic debug: thread started
[00000441] main generic debug: thread 2907749264 (fontlist builder) created at priority 0 (freetype.c:477)
[00000439] freetype spu text debug: using fontsize: 18
[00000439] main spu text debug: using text renderer module "freetype"
[00000439] main spu text debug: TIMER module_Need() : 63.409 ms - Total 63.409 ms / 1 intvls (Avg 63.409 ms)
[00000439] freetype spu text debug: using fontsize: 18
[00000441] freetype generic debug: Building font database...
[00000441] freetype generic debug: Finished building font database.
[00000441] freetype generic debug: Took 0 seconds
[00000441] main generic debug: thread ended
[00000438] main blend debug: looking for video blending module: 1 candidate
[00000438] blend blend debug: chroma: YUVA -> I420
[00000438] main blend debug: using video blending module "blend"
[00000438] main blend debug: TIMER module_Need() : 22.734 ms - Total 22.734 ms / 1 intvls (Avg 22.734 ms)
[00000420] main video output warning: late picture skipped (25605)
[00000420] main video output warning: late picture skipped (-10567)
[00000419] main audio output debug: removing module "float32_mixer"
[00000419] main audio output debug: looking for audio mixer module: 3 candidates
[00000419] main audio output debug: using audio mixer module "trivial_mixer"
[00000419] main audio output debug: TIMER module_Need() : 7.337 ms - Total 7.337 ms / 1 intvls (Avg 7.337 ms)
[00000419] main audio output debug: removing module "trivial_mixer"
[00000419] main audio output debug: looking for audio mixer module: 3 candidates
[00000419] main audio output debug: using audio mixer module "trivial_mixer"
[00000419] main audio output debug: TIMER module_Need() : 12.138 ms - Total 12.138 ms / 1 intvls (Avg 12.138 ms)
[00000392] qt4 interface debug: Error while initializing qt-specific localization
[00000392] qt4 interface debug: Updating the stream status: 3
[00000401] main decoder debug: decoded 103/105 pictures
[00000420] main video output warning: late picture skipped (29806)
[00000420] main video output warning: late picture skipped (47620)
[00000420] main video output warning: late picture skipped (9506)
[00000420] main video output warning: late picture skipped (114962)
[00000420] main video output warning: late picture skipped (76296)
[00000420] main video output warning: late picture skipped (35502)
[00000420] main video output warning: late picture skipped (16760)
[00000420] main video output warning: late picture skipped (115113)
[00000420] main video output warning: late picture skipped (76015)
[00000420] main video output warning: late picture skipped (36178)
[00000420] main video output warning: late picture skipped (20507)
[00000401] main decoder debug: decoded 103/105 pictures
[00000419] main audio output warning: resampling stopped after 18550332 usec (drift: 9228)
[00000420] main video output warning: late picture skipped (48234)
[00000420] main video output warning: late picture skipped (8813)
[00000420] main video output warning: late picture skipped (2582)
[00000420] main video output warning: late picture skipped (23053)
[00000395] main input debug: EOF reached
[00000395] main input debug: waiting decoder fifos to empty
[00000420] main video output warning: late picture skipped (32126)
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000420] main video output warning: late picture skipped (148067)
[00000420] main video output warning: late picture skipped (108218)
[00000420] main video output warning: late picture skipped (68306)
[00000420] main video output warning: late picture skipped (49112)
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000420] main video output warning: late picture skipped (19743)
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000395] main input debug: waiting decoder fifos to empty
[00000420] main video output warning: late picture skipped (17151)
[00000392] qt4 interface debug: New Event: type 1103
[00000357] main playlist debug: finished input
[00000396] main demux debug: waitpipe: object killed
[00000357] main playlist debug: dying input
[00000396] rtp demux debug: removing RTP source (b3ee53bc)
[00000399] ts demux debug: eof ?
[00000398] main stream debug: thread ended
[00000398] main stream debug: thread 2968935312 joined (input/demux.c:385)
[00000399] ts demux debug: pid list:
[00000399] ts demux debug: - pid[0] seen
[00000399] ts demux debug: - pid[66] seen
[00000401] main decoder debug: removing module "libmpeg2"
[00000401] main decoder debug: thread ended
[00000401] main decoder debug: thread 2957396880 joined (input/decoder.c:248)
[00000401] main decoder debug: killing decoder fourcc `mpgv', 0 PES in FIFO
[00000392] message interface warning: message queue overflowed
[00000392] qt4 interface debug: Updating the stream status: 8
[00000357] main playlist debug: dying input
[00000357] main playlist debug: dying input
[00000419] alsa audio output debug: recovered from buffer underrun
[00000420] main video output debug: thread ended
[00000420] main video output debug: thread 2917632912 joined (video_output/video_output.c:536)
[00000420] main video output debug: removing module "xvideo"
[00000438] main blend debug: removing module "blend"
[00000441] main generic debug: thread 2907749264 joined (freetype.c:511)
[00000357] main playlist debug: dying input
[00000439] main spu text debug: removing module "freetype"
[00000399] ts demux debug: - pid[68] seen
[00000407] main decoder debug: removing module "mpeg_audio"
[00000407] main decoder debug: thread ended
[00000407] main decoder debug: thread 2947546000 joined (input/decoder.c:248)
[00000407] main decoder debug: killing decoder fourcc `mpga', 0 PES in FIFO
[00000424] main audio output debug: removing module "mpgatofixed32"
[00000431] main audio output debug: removing module "bandlimited_resampler"
[00000419] main audio output debug: thread ended
[00000419] main audio output debug: thread 2926230416 joined (alsa.c:742)
[00000419] main audio output debug: removing module "alsa"
[00000419] main audio output debug: removing module "trivial_mixer"
[00000395] main input debug: Program doesn't contain anymore ES
[00000399] ts demux debug: - pid[69] seen
[00000399] ts demux debug: - pid[8191] seen
[00000399] main demux debug: removing module "ts"
[00000396] main demux debug: removing module "rtp"
[00000395] main input debug: thread ended
[00000357] main playlist debug: dead input
[00000395] main input debug: thread 2977835920 joined (playlist/engine.c:244)
[00000395] main input debug: TIMER input launching for '127.0.0.1:1234' : 94.305 ms - Total 94.305 ms / 1 intvls (Avg 94.305 ms)
[00000357] main playlist debug: starting new item
[00000357] main playlist debug: changing item without a request (current 0/1)
[00000357] main playlist debug: nothing to play
[00000001] main libvlc debug: removing all interfaces
[00000392] qt4 interface debug: Quitting the Qt4 Interface
[00000392] qt4 interface debug: Destroying the main interface
[00000392] qt4 interface debug: Destroying the Dialog Provider
[00000392] main interface debug: thread ended
[00000392] main interface debug: thread 2986228624 joined (interface/interface.c:188)
[00000392] main interface debug: removing module "qt4"
[00000390] main interface debug: thread ended
[00000390] main interface debug: thread 3008801680 joined (interface/interface.c:188)
[00000390] main interface debug: removing module "signals"
[00000388] main interface debug: thread ended
[00000388] main interface debug: thread 3025587088 joined (interface/interface.c:188)
[00000388] main interface debug: removing module "screensaver"
[00000386] main interface debug: thread ended
[00000386] main interface debug: thread 3033979792 joined (interface/interface.c:188)
[00000386] main interface debug: removing module "inhibit"
[00000384] main interface debug: thread ended
[00000384] main interface debug: thread 3042372496 joined (interface/interface.c:188)
[00000384] main interface debug: removing module "hotkeys"
[00000001] main libvlc debug: removing all services discovery tasks
[00000001] main libvlc debug: removing playlist
[00000357] main playlist debug: saving Media Library to file /home/sblee/.local/share/vlc/ml.xspf
[00000357] main playlist debug: looking for playlist export module: 1 candidate
[00000357] main playlist debug: using playlist export module "export"
[00000357] main playlist debug: TIMER module_Need() : 2.133 ms - Total 2.133 ms / 1 intvls (Avg 2.133 ms)
[00000357] main playlist debug: removing module "export"
[00000382] main preparser debug: thread ended
[00000382] main preparser debug: thread 3073309584 joined (playlist/engine.c:521)
[00000383] main fetcher debug: thread ended
[00000383] main fetcher debug: thread 3059157904 joined (playlist/engine.c:523)
[00000357] main playlist debug: thread ended
[00000357] main playlist debug: thread 3050765200 joined (libvlc.c:993)
[00000382] main preparser debug: Destroyed
[00000383] main fetcher debug: Destroyed
[00000357] main playlist debug: Destroyed
[00000001] main libvlc debug: removing interaction
[00000356] main interaction debug: thread ended
[00000356] main interaction debug: thread 3081702288 joined (interface/interaction.c:400)
[00000001] main libvlc debug: removing all video outputs
[00000001] main libvlc debug: TIMER ML Load : Total 72.611 ms / 1 intvls (Avg 72.611 ms)
[00000001] main libvlc debug: TIMER Items array build : Total 3.980 ms / 2 intvls (Avg 1.990 ms)
[00000001] main libvlc debug: TIMER ML Dump : Total 4.681 ms / 1 intvls (Avg 4.681 ms)
[00000001] main libvlc debug: removing stats
[00000001] main libvlc debug: removing module "memcpymmxext"
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: writing plugins cache /home/sblee/.cache/vlc/plugins-04041e.dat

Streamer log with CCID2
VLC media player 0.9.8a Grishenko
[00000001] main libvlc debug: VLC media player - version 0.9.8a Grishenko - (c) 1996-2008 the VideoLAN team
[00000001] main libvlc debug: libvlc was configured with ./configure '--prefix=/usr'
[00000001] main libvlc debug: translation test: code is "C"
[00000001] main libvlc debug: checking builtin modules
[00000001] main libvlc debug: checking plugin modules
[00000001] main libvlc debug: loading plugins cache file /home/sblee/.cache/vlc/plugins-04041e.dat
[00000001] main libvlc debug: recursively browsing `/usr/lib/vlc'
[00000001] main libvlc debug: recursively browsing `./modules'
[00000001] main libvlc debug: module bank initialized, found 263 modules
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU
[00000001] main libvlc debug: looking for memcpy module: 3 candidates
[00000001] main libvlc debug: using memcpy module "memcpymmxext"
[00000356] main interaction debug: thread started
[00000356] main interaction debug: thread 3081624464 (Interaction control) created at priority 0 (interface/interaction.c:382)
[00000358] main input debug: Creating an input for 'Media Library'
[00000358] main input debug: Input is a meta file: disabling unneeded options
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' gives access `file' demux `xspf-open' path `/home/sblee/.local/share/vlc/ml.xspf'
[00000358] main input debug: creating access 'file' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: looking for access module: 3 candidates
[00000359] access_file access debug: opening file `/home/sblee/.local/share/vlc/ml.xspf'
[00000359] main access debug: using access module "access_file"
[00000359] main access debug: TIMER module_Need() : 13.846 ms - Total 13.846 ms / 1 intvls (Avg 13.846 ms)
[00000364] main stream debug: Using AStream*Stream
[00000364] main stream debug: pre-buffering...
[00000364] main stream debug: received first data for our buffer
[00000358] main input debug: creating demux: access='file' demux='xspf-open' path='/home/sblee/.local/share/vlc/ml.xspf'
[00000365] main demux debug: looking for demux module: 1 candidate
[00000365] playlist demux debug: using XSPF playlist reader
[00000365] main demux debug: using demux module "playlist"
[00000365] main demux debug: TIMER module_Need() : 7.536 ms - Total 7.536 ms / 1 intvls (Avg 7.536 ms)
[00000358] main input debug: `file/xspf-open:///home/sblee/.local/share/vlc/ml.xspf' successfully opened
[00000380] main xml debug: looking for xml module: 2 candidates
[00000380] main xml debug: using xml module "xml"
[00000380] main xml debug: TIMER module_Need() : 4.382 ms - Total 4.382 ms / 1 intvls (Avg 4.382 ms)
[00000365] playlist demux debug: parsed 0 tracks successfully
[00000380] main xml debug: removing module "xml"
[00000358] main input debug: EOF reached
[00000358] main input debug: control type=1
[00000365] main demux debug: removing module "playlist"
[00000359] main access debug: removing module "access_file"
[00000358] main input debug: TIMER input launching for 'Media Library' : 47.209 ms - Total 47.209 ms / 1 intvls (Avg 47.209 ms)
[00000382] main preparser debug: thread started
[00000382] main preparser debug: waiting for thread initialization
[00000382] main preparser debug: thread 3073231760 (preparser) created at priority 0 (playlist/thread.c:79)
[00000383] main fetcher debug: thread started
[00000383] main fetcher debug: waiting for thread initialization
[00000383] main fetcher debug: thread 3059080080 (fetcher) created at priority 0 (playlist/thread.c:108)
[00000357] main playlist debug: thread started
[00000357] main playlist debug: waiting for thread initialization
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 0 items, index -1
[00000357] main playlist debug: thread 3050687376 (playlist) created at priority 0 (playlist/thread.c:117)
[00000384] main interface debug: looking for interface module: 1 candidate
[00000384] main interface debug: using interface module "hotkeys"
[00000384] main interface debug: TIMER module_Need() : 7.467 ms - Total 7.467 ms / 1 intvls (Avg 7.467 ms)
[00000384] main interface debug: thread started
[00000384] main interface debug: thread 3042294672 (interface) created at priority 0 (interface/interface.c:168)
[00000386] main interface debug: looking for interface module: 1 candidate
[00000386] main interface debug: using interface module "inhibit"
[00000386] main interface debug: TIMER module_Need() : 17.479 ms - Total 17.479 ms / 1 intvls (Avg 17.479 ms)
[00000386] main interface debug: thread started
[00000386] main interface debug: thread 3033901968 (interface) created at priority 0 (interface/interface.c:168)
[00000388] main interface debug: looking for interface module: 1 candidate
[00000388] main interface debug: using interface module "screensaver"
[00000388] main interface debug: TIMER module_Need() : 4.415 ms - Total 4.415 ms / 1 intvls (Avg 4.415 ms)
[00000388] main interface debug: thread started
[00000388] main interface debug: thread 3025509264 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: adding item `test.mpg' ( ../test.mpg )
[00000357] main playlist debug: rebuilding array of current - root Playlist
[00000357] main playlist debug: rebuild done - 1 items, index -1
[00000390] main interface debug: looking for interface module: 20 candidates
[00000390] main interface debug: using interface module "signals"
[00000390] main interface debug: TIMER module_Need() : 7.812 ms - Total 7.812 ms / 1 intvls (Avg 7.812 ms)
[00000390] main interface debug: thread started
[00000390] main interface debug: thread 3008723856 (interface) created at priority 0 (interface/interface.c:168)
[00000001] main libvlc: Running vlc with the default interface. Use 'cvlc' to use vlc without interface.
[00000392] main interface debug: looking for interface module: 3 candidates
[00000392] main interface debug: using interface module "qt4"
[00000392] main interface debug: TIMER module_Need() : 203.201 ms - Total 203.201 ms / 1 intvls (Avg 203.201 ms)
[00000392] main interface debug: thread started
[00000392] main interface debug: thread 2986150800 (interface) created at priority 0 (interface/interface.c:168)
[00000357] main playlist debug: starting new item
[00000357] main playlist debug: processing request item null node Playlist skip 0
[00000357] main playlist debug: creating new input thread
[00000395] main input debug: Creating an input for 'test.mpg'
[00000395] main input debug: thread started
[00000395] main input debug: waiting for thread initialization
[00000396] main stream output debug: stream=`rtp'
[00000397] main stream out debug: looking for sout stream module: 1 candidate
[00000395] main input debug: thread 2977758096 (input) created at priority 10 (input/input.c:370)
[00000397] main stream out debug: set config option: sout-rtp-dst to 127.0.0.1
[00000397] main stream out debug: set config option: sout-rtp-port to 1234
[00000397] main stream out debug: set config option: sout-rtp-mux to ts
[00000397] main stream out debug: set config option: sout-rtp-proto to dccp
[00000400] main mux debug: looking for sout mux module: 1 candidate
[00000400] mux_ts mux debug: shaping=200000 pcr=70000 dts_delay=400000
[00000400] main mux debug: using sout mux module "mux_ts"
[00000400] main mux debug: TIMER module_Need() : 118.211 ms - Total 118.211 ms / 1 intvls (Avg 118.211 ms)
[00000396] main stream output debug: muxer support adding stream at any time
[00000396] main stream output debug: muxer prefers to wait for all ES before starting to mux
[00000397] stream_out_rtp stream out debug: maximum RTP packet size: 1400 bytes
[00000397] main stream out debug: net: listening to 127.0.0.1 port 1234
[00000402] main generic debug: thread started
[00000402] main generic debug: thread 2968255376 (RTP send thread) created at priority 20 (rtp.c:1235)
[00000397] stream_out_rtp stream out debug: sdp=
v=0
o=- 14796519497695140187 14796519497695140187 IN IP4 shuttlex
s=Unnamed
i=N/A
c=IN IP4 127.0.0.1
t=0 0
a=tool:vlc 0.9.8a
a=recvonly
a=type:broadcast
a=charset:UTF-8
a=rtcp-mux
m=video 1234 DCCP/RTP/AVP 33
b=RR:0
a=rtpmap:33 MP2T/90000
a=setup:passive
a=dccp-service-code:SC:RTPV

[00000397] main stream out debug: using sout stream module "stream_out_rtp"
[00000397] main stream out debug: TIMER module_Need() : 295.980 ms - Total 295.980 ms / 1 intvls (Avg 295.980 ms)
[00000395] main input debug: `../test.mpg' gives access `' demux `' path `../test.mpg'
[00000395] main input debug: creating demux: access='' demux='' path='../test.mpg'
[00000403] main demux debug: looking for access_demux module: 2 candidates
[00000403] main demux debug: TIMER module_Need() : 11.594 ms - Total 11.594 ms / 1 intvls (Avg 11.594 ms)
[00000395] main input debug: creating access '' path='../test.mpg'
[00000407] main access debug: looking for access module: 5 candidates
[00000407] vcd access debug: trying .cue file: ../test.cue
[00000407] vcd access debug: could not find .cue file
[00000407] access_file access debug: opening file `../test.mpg'
[00000407] main access debug: using access module "access_file"
[00000407] main access debug: TIMER module_Need() : 15.136 ms - Total 15.136 ms / 1 intvls (Avg 15.136 ms)
[00000409] main stream debug: Using AStream*Stream
[00000409] main stream debug: pre-buffering...
[00000409] main stream debug: received first data for our buffer
[00000409] main stream debug: pre-buffering done 1245146 bytes in 0s - 10134 kbytes/s
[00000395] main input debug: creating demux: access='' demux='' path='../test.mpg'
[00000410] main demux debug: looking for demux module: 52 candidates
[00000410] main demux debug: using demux module "ps"
[00000410] main demux debug: TIMER module_Need() : 180.573 ms - Total 180.573 ms / 1 intvls (Avg 180.573 ms)
[00000395] main input debug: looking for a subtitle file in ../
[00000395] main input debug: starting in async mode
[00000395] main input debug: `../test.mpg' successfully opened
[00000410] ps demux warning: garbage at input, trying to resync...
[00000410] ps demux warning: found sync code
[00000410] ps demux debug: we found a length of: 900556033
[00000395] main input debug: control type=1
[00000395] main input debug: selecting program id=0
[00000443] main packetizer debug: looking for packetizer module: 17 candidates
[00000443] main packetizer debug: using packetizer module "mpeg_audio"
[00000443] main packetizer debug: TIMER module_Need() : 44.876 ms - Total 44.876 ms / 1 intvls (Avg 44.876 ms)
[00000395] main input debug: stream out mode -> no decoder thread
[00000472] main packetizer debug: looking for packetizer module: 17 candidates
[00000472] main packetizer debug: using packetizer module "packetizer_mpegvideo"
[00000472] main packetizer debug: TIMER module_Need() : 1.963 ms - Total 1.963 ms / 1 intvls (Avg 1.963 ms)
[00000395] main input debug: stream out mode -> no decoder thread
[00000472] packetizer_mpegvideo packetizer debug: size 352x288 fps=25.000
[00000396] main stream output debug: adding a new sout input (sout_input:0x8a39668)
[00000400] main mux debug: adding a new input
[00000400] mux_ts mux debug: adding input codec=mpgv pid=68
[00000400] mux_ts mux debug: new PCR PID is 68
[00000472] main packetizer debug: switching to sync mode
[00000443] mpeg_audio packetizer debug: MPGA channels:2 samplerate:44100 bitrate:128
[00000396] main stream output debug: adding a new sout input (sout_input:0x8a445e0)
[00000400] main mux debug: adding a new input
[00000400] mux_ts mux debug: adding input codec=mpga pid=69
[00000392] qt4 interface debug: Error while initializing qt-specific localization
[00000392] qt4 interface debug: Updating the stream status: 3
[00000402] main generic debug: accepted socket 22 (from socket 11)
[00000402] stream_out_rtp generic debug: adding socket 22
[00000357] main playlist debug: incoming request - stopping current input
[00000357] main playlist debug: dying input
[00000001] main libvlc debug: removing all interfaces
[00000395] main input debug: control type=0
[00000395] main input debug: control: stopping input
[00000392] qt4 interface debug: Quitting the Qt4 Interface
[00000392] qt4 interface debug: Destroying the main interface
[00000392] qt4 interface debug: Destroying the Dialog Provider
[00000443] main packetizer debug: removing module "mpeg_audio"
[00000443] main packetizer debug: killing decoder fourcc `mpga', 0 PES in FIFO
[00000396] main stream output debug: removing a sout input (sout_input:0x8a445e0)
[00000400] mux_ts mux debug: removing input pid=69
[00000472] main packetizer debug: removing module "packetizer_mpegvideo"
[00000472] main packetizer debug: killing decoder fourcc `mpgv', 0 PES in FIFO
[00000396] main stream output debug: removing a sout input (sout_input:0x8a39668)
[00000400] mux_ts mux debug: removing input pid=68
[00000400] mux_ts mux debug: new PCR PID is 8191
[00000400] main mux warning: no more input streams for this mux
[00000395] main input debug: Program doesn't contain anymore ES
[00000410] main demux debug: removing module "ps"
[00000407] main access debug: removing module "access_file"
[00000395] main input debug: thread ended
[00000357] main playlist debug: dead input
[00000395] main input debug: thread 2977758096 joined (playlist/engine.c:244)
[00000395] main input debug: TIMER input launching for 'test.mpg' : 663.144 ms - Total 663.144 ms / 1 intvls (Avg 663.144 ms)
[00000397] main stream out debug: destroying chain... (name=rtp)
[00000402] main generic debug: waitpipe: object killed
[00000402] main generic debug: thread ended
[00000402] main generic debug: thread 2968255376 joined (rtp.c:1271)
[00000400] main mux debug: removing module "mux_ts"
[00000397] main stream out debug: removing module "stream_out_rtp"
[00000397] main stream out debug: destroying chain done
[00000392] main interface debug: thread ended
[00000392] main interface debug: thread 2986150800 joined (interface/interface.c:188)
[00000392] main interface debug: removing module "qt4"
[00000390] main interface debug: thread ended
[00000390] main interface debug: thread 3008723856 joined (interface/interface.c:188)
[00000390] main interface debug: removing module "signals"
[00000388] main interface debug: thread ended
[00000388] main interface debug: thread 3025509264 joined (interface/interface.c:188)
[00000388] main interface debug: removing module "screensaver"
[00000386] main interface debug: thread ended
[00000386] main interface debug: thread 3033901968 joined (interface/interface.c:188)
[00000386] main interface debug: removing module "inhibit"
[00000384] main interface debug: thread ended
[00000384] main interface debug: thread 3042294672 joined (interface/interface.c:188)
[00000384] main interface debug: removing module "hotkeys"
[00000001] main libvlc debug: removing all services discovery tasks
[00000001] main libvlc debug: removing playlist
[00000357] main playlist debug: saving Media Library to file /home/sblee/.local/share/vlc/ml.xspf
[00000357] main playlist debug: looking for playlist export module: 1 candidate
[00000357] main playlist debug: using playlist export module "export"
[00000357] main playlist debug: TIMER module_Need() : 1.376 ms - Total 1.376 ms / 1 intvls (Avg 1.376 ms)
[00000357] main playlist debug: removing module "export"
[00000382] main preparser debug: thread ended
[00000382] main preparser debug: thread 3073231760 joined (playlist/engine.c:521)
[00000383] main fetcher debug: thread ended
[00000383] main fetcher debug: thread 3059080080 joined (playlist/engine.c:523)
[00000357] main playlist debug: thread ended
[00000357] main playlist debug: thread 3050687376 joined (libvlc.c:993)
[00000382] main preparser debug: Destroyed
[00000383] main fetcher debug: Destroyed
[00000357] main playlist debug: Destroyed
[00000001] main libvlc debug: removing interaction
[00000356] main interaction debug: thread ended
[00000356] main interaction debug: thread 3081624464 joined (interface/interaction.c:400)
[00000001] main libvlc debug: removing all video outputs
[00000001] main libvlc debug: TIMER ML Load : Total 64.103 ms / 1 intvls (Avg 64.103 ms)
[00000001] main libvlc debug: TIMER Items array build : Total 2.651 ms / 2 intvls (Avg 1.325 ms)
[00000001] main libvlc debug: TIMER ML Dump : Total 1.999 ms / 1 intvls (Avg 1.999 ms)
[00000001] main libvlc debug: removing stats
[00000001] main libvlc debug: removing module "memcpymmxext"
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: opening config file (/home/sblee/.config/vlc/vlcrc)
[00000001] main libvlc debug: writing plugins cache /home/sblee/.cache/vlc/plugins-04041e.dat

[Rémi Denis-Courmont]

The TS parser goes further and adds elementary streams. I don't know why it fails with CCID2. Possibly the link gets congested and too many packets are dropped.

[sblee]

When DCCP is set as CCID2, video and audio are provided. However, CCID3 don't.

Congestion could be one possible answer but the tests are actually using loopback (i.e. 127.0.0.1)

In addition, simple DCCP test (using ttcp) shows no problem with both CCID2 and CCID3.

I'm really novice for vlc source code but which part I can check first?

[Rémi Denis-Courmont]

Congestion could be one possible answer but the tests are actually using loopback (i.e. 127.0.0.1)

In addition, simple DCCP test (using ttcp) shows no problem with both CCID2 and CCID3.

At what bandwidth? The VLC server will discard a packet if the socket is congested for performance reasons.

I'm really novice for vlc source code but which part I can check first?

That would be modules/stream_out/rtp.c and modules/demux/rtp.c.

[sblee]

Thank you for the comment. I think I found the problem.

In modules/stream_output/rtp.c, ThreadSend() only tries twice to send data as followed.

Code: Select all

if( send( id->sinkv[i].rtp_fd, out->p_buffer, len, 0 ) >= 0 ) continue; /* Retry sending to root out soft-errors */ if( send( id->sinkv[i].rtp_fd, out->p_buffer, len, 0 ) >= 0 ) continue;

Because of this, DCCP CCID 3 does not send data and the socket is closed as it is shown in the streamer log.

[00000402] stream_out_rtp generic debug: removing socket 22

I changed the above code as below.

Code: Select all

int status; do { status = send( id->sinkv[i].rtp_fd, out->p_buffer, len, 0 ); } while((status<0)&&(errno == EAGAIN)); if( status ) { continue; }

Currently, this code works with UDP, DCCP CCID2, DCCP CCID3, although UDP does not look like use this part of code.

[Rémi Denis-Courmont]

The sending thread CANNOT sleep. We may have more than one client here. This would be a trivial denial of service attack.

If the connection is truly congested, we need to drop packets anyway. For slow start and transient problems, use adequate socket send buffers.

[sblee]

Right. I agree that it might be related to transient or slow start of dccp.

However, one another trivial thing is that the socket connection is considered as dead although it is in just temporarily in bad status. Because of this reason, dccp ccid3 does not work with the current implementation.

In other words, receiver can get the data over dccp ccid3 if streamer does not remove socket just with disabling following code.

Code: Select all

//deadv[deadc++] = id->sinkv[i].rtp_fd;

Actually, it is also possible temporary solution as it works fine currently only for my tests.

As you commented, sending thread may discard or store packets when send() is not ready to work. However, there should be some way to keep the socket alive until dccp ccid 3 link reaches to stable status.

I also found quite similar issue which was in gstreamer from a web page, http://www.mail-archive.com/dccp@vger.k ... 02675.html.

[Rémi Denis-Courmont]

What you describe looks like bugs in the socket API implementation of CCID3, not in VLC.

The only valid transient errors on a connected socket are EAGAIN and EINTR. All other errors are fatal and any sane application will terminate the connection.

[sblee]

Frankly, I wonder that is the dccp ccid 3 socket bug. If the application need to handle EAGAIN error, this might be vlc bug.

In module/stream_out/rtp.c, ThreadSend() does not handle EAGAIN and disconnect the socekt. Since this is the reason, the while loop checking EAGAIN works with dccp ccid 3.

Actually, they describe this issue as below in http://www.mail-archive.com/dccp@vger.k ... 02675.html.

This problem (or "feature") is well-known, Ian has also repeatedly pointed out
to this. Basically, what your code needs to be able to do is cope with EAGAIN,
i.e. when the TX buffer is full and CCID3 decides to throttle the sending speed,
the write call needs to be repeated.

[Rémi Denis-Courmont]

We should indeed not discard the socket because of transient errors such as EAGAIN, ENOMEM or ICMP. I will try to fix it.

That being noted, we are not going to try to re-send. If the socket is too slow, it is too slow. We are dealing with real-time traffic here. Besides, the overhead would be too high, especially considering that it's really only for DCCP, which is not very common.

[sblee]

Thank you for your response!

[Rémi Denis-Courmont]

Should be fixed in 0.9.9 anyway

[oudomkeo]

Hello guys, My name is Dom,

Lately I'm interested with streaming the video over DCCP by using VLC, but I don't know how to make VLC support DCCP because I am lack of experience on this research. Please kindly help me with that. I attempt to use VLC 0.9.8a for streaming.

My email is: keo.oudom@gmail.com
Thank you so much.