The VideoLAN Forums

Posted: **26 Aug 2009 16:24**

Hello all,

I have been having a problem that came from nowhere since yesterday, here is a segment of my command line:

:rtp{dst=192.168.0.113,mux=ts,proto=udp,dscp=184,ttl=16,mtu=1500,sdp=file://c:/live.sdp}

Complete command line:

-vvv --extraintf=logger --logfile=C:\_cpp\Projects\Webvox_Encoder\Webvox_Encoder\Webvox_Encoder\bin\Release\logs\debug.log --plugin-path=C:\Program Files\VideoLAN\VLC\plugins --ignore-config --sout-transcode-high-priority --sout-mux-caching=1000 --sout-udp-caching=1000 --udp-caching=1000 --tcp-caching=1000 --rtp-caching=1000 --rtsp-caching=1000 --http-caching=1000 --mms-caching=1000 -I rc dshow:// :dshow-vdev=Logitech QuickCam Communicate STX :dshow-adev=Audio Bluetooth :dshow-caching=5000 --aspect-ratio=16:9 --video-title=Logitech QuickCam Communicate STX --no-one-instance --overlay --sout=#duplicate{dst=display,dst="transcode{width=480,height=270,threads=16,vcodec=x264,venc=x264{8x8dct=0,aq-mode=2,aq-strength=1.0,asm=1,aud=0,bframes=0,bpyramid=0,b-adapt=1,b-bias=0,cabac=1,chroma-me=1,chroma-qp-offset=0,cplxblur=20.0,crf=0,dct-decimate=1,deadzone-inter=21,deadzone-intra=11,deblock=0:0,direct=spatial,direct-8x8=-1,fast-pskip=1,interlaced=0,ipratio=1.40,keyint=250,level=5.1,me=hex,merange=16,min-keyint=25,mixed-refs=0,mvrange=-1,mvrange-thread=-1,nf=0,non-deterministic=0,nr=0,partitions=normal,pass=0,pbratio=1.30,psnr=0,qblur=0.5,qp=26,qcomp=0.60,qpstep=4,qpmax=51,qpmin=10,quiet=0,ratetol=1.0,ref=1,scenecut=40,sps-id=0,ssim=0,stats=x264_2pass.log,subme=5,trellis=0,verbose=0,vbv-bufsize=0,vbv-init=0.9,vbv-maxrate=0,weightb=0},vb=5000,scale=1,fps=29.97,acodec=mp4a,ab=64,channels=2,samplerate=44100}:rtp{dst=192.168.0.113,mux=ts,proto=udp,dscp=184,ttl=16,mtu=1500,sdp=file://c:/live.sdp}

This is causing lofs of contants:

Code: Select all

stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (683945)
main warning: late buffer for mux input (685980)
main warning: late buffer for mux input (714014)
main warning: late buffer for mux input (538903)
main warning: late buffer for mux input (565938)
main warning: late buffer for mux input (584972)
main warning: late buffer for mux input (588007)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (617041)
main warning: late buffer for mux input (610076)
main warning: late buffer for mux input (562587)
main warning: late buffer for mux input (617622)
main warning: late buffer for mux input (585656)
main warning: late buffer for mux input (612691)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (607725)
main warning: late buffer for mux input (634760)
main warning: late buffer for mux input (721277)
main warning: late buffer for mux input (690312)
main warning: late buffer for mux input (600570)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (594604)
main warning: late buffer for mux input (622639)
main warning: late buffer for mux input (707156)
main warning: late buffer for mux input (678190)
main warning: late buffer for mux input (706225)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (585859)
main warning: late buffer for mux input (610893)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (738410)
main warning: late buffer for mux input (766445)
main warning: late buffer for mux input (794962)

What I have just found is if I remove mux=ts it doesn't warn me anymore... Anyone knows why this is happening? Also what is the default muxer for this if it's not TS?

Thanks for help

-fred

Posted: **27 Aug 2009 14:11**

Also what is the default muxer for this if it's not TS?

Message log should tell you that.

Posted: **27 Aug 2009 15:42**

Also what is the default muxer for this if it's not TS?
Message log should tell you that.

I have read it entirely and could not find it. I have fixed the "main warning: late buffer for mux input (1158968)" problem by adding --dshow-caching=3000 but now I have lots of "stream_out_transcode debug: drift is too high, resetting master sync" and I don't know why.

New commandline:

Code: Select all

-vvv --extraintf=logger --logfile=C:\_cpp\Projects\debug.log --plugin-path=C:\Program Files\VideoLAN\VLC\plugins --ignore-config --sout-mux-caching=1000 --sout-udp-caching=1000 --udp-caching=1000 --tcp-caching=1000 --rtp-caching=1000 --rtsp-caching=1000 --http-caching=1000 --mms-caching=1000 -I rc dshow:// :dshow-vdev=Logitech QuickCam Communicate STX :dshow-adev=Audio Bluetooth --dshow-caching=3000 --aspect-ratio=16:9 --video-title=Logitech QuickCam Communicate STX --no-one-instance --overlay --quiet-synchro --vout-event=2 --clock-synchro=0 --sout=#duplicate{dst=display,dst="transcode{width=480,height=270,threads=32,vcodec=x264,venc=x264{8x8dct=0,aq-mode=2,aq-strength=1.0,asm=1,aud=0,bframes=0,bpyramid=0,b-adapt=1,b-bias=0,cabac=1,chroma-me=1,chroma-qp-offset=0,cplxblur=20.0,crf=0,dct-decimate=1,deadzone-inter=21,deadzone-intra=11,deblock=0:0,direct=spatial,direct-8x8=-1,fast-pskip=1,interlaced=0,ipratio=1.40,keyint=250,level=5.1,me=hex,merange=16,min-keyint=25,mixed-refs=0,mvrange=-1,mvrange-thread=-1,nf=0,non-deterministic=0,nr=0,partitions=normal,pass=0,pbratio=1.30,psnr=0,qblur=0.5,qp=26,qcomp=0,qpstep=4,qpmax=51,qpmin=10,quiet=0,ratetol=0,ref=1,scenecut=40,sps-id=0,ssim=0,stats=x264_2pass.log,subme=5,trellis=0,verbose=0,vbv-bufsize=0,vbv-init=0.9,vbv-maxrate=5000,weightb=0},vb=2500,scale=0.7,fps=15,audio-sync,acodec=mp4a,ab=64,channels=2,samplerate=44100}:rtp{dst=192.168.0.113,mux=ts,proto=udp,dscp=184,ttl=16,mtu=1500,sdp=file://c:/live.sdp}

Here is the -vvv log:

Code: Select all

-- logger module started --
main debug: using interface module "logger"
main debug: TIMER module_need() : 27,000 ms - Total 27,000 ms / 1 intvls (Avg 27,000 ms)
main debug: thread (interface) created at priority 0 (../.././src/interface/interface.c:151)
main debug: looking for interface module: 1 candidate
main debug: thread started
main debug: thread ended
main debug: using interface module "hotkeys"
main debug: TIMER module_need() : 39,000 ms - Total 39,000 ms / 1 intvls (Avg 39,000 ms)
main debug: thread started
main debug: thread (interface) created at priority 0 (../.././src/interface/interface.c:151)
main debug: adding item `dshow://' ( dshow:// )
main debug: rebuilding array of current - root Playlist
main debug: rebuild done - 1 items, index -1
main debug: Creating an input for 'dshow://'
main debug: thread (input) created at priority 1 (../.././src/input/input.c:230)
main debug: thread started
main debug: stream=`duplicate'
main debug: looking for sout stream module: 1 candidate
stream_out_duplicate debug: creating 'duplicate'
stream_out_duplicate debug:  * adding `display'
main debug: stream=`display'
main debug: looking for sout stream module: 1 candidate
main debug: using sout stream module "stream_out_display"
main debug: TIMER module_need() : 4,000 ms - Total 4,000 ms / 1 intvls (Avg 4,000 ms)
stream_out_duplicate debug:  * adding `transcode{width=480,height=270,threads=32,vcodec=x264,venc=x264{8x8dct=0,aq-mode=2,aq-strength=1.0,asm=1,aud=0,bframes=0,bpyramid=0,b-adapt=1,b-bias=0,cabac=1,chroma-me=1,chroma-qp-offset=0,cplxblur=20.0,crf=0,dct-decimate=1,deadzone-inter=21,deadzone-intra=11,deblock=0:0,direct=spatial,direct-8x8=-1,fast-pskip=1,interlaced=0,ipratio=1.40,keyint=250,level=5.1,me=hex,merange=16,min-keyint=25,mixed-refs=0,mvrange=-1,mvrange-thread=-1,nf=0,non-deterministic=0,nr=0,partitions=normal,pass=0,pbratio=1.30,psnr=0,qblur=0.5,qp=26,qcomp=0,qpstep=4,qpmax=51,qpmin=10,quiet=0,ratetol=0,ref=1,scenecut=40,sps-id=0,ssim=0,stats=x264_2pass.log,subme=5,trellis=0,verbose=0,vbv-bufsize=0,vbv-init=0.9,vbv-maxrate=1000,weightb=0},vb=500,scale=0.7,fps=15,audio-sync,acodec=mp4a,ab=64,channels=2,samplerate=44100}:rtp{dst=192.168.0.113,mux=ts,proto=udp,dscp=184,ttl=16,mtu=1500,sdp=file://c:/live.sdp'
main debug: stream=`transcode'
main debug: looking for sout stream module: 1 candidate
main debug: stream=`rtp'
main debug: looking for sout stream module: 1 candidate
main debug: set config option: sout-rtp-dst to 192.168.0.113
main debug: set config option: sout-rtp-mux to ts
main debug: set config option: sout-rtp-proto to udp
main warning: option dscp is unknown
main debug: set config option: sout-rtp-ttl to 16
main warning: option mtu is unknown
main debug: set config option: sout-rtp-sdp to file://c:/live.sdp
main debug: looking for sout mux module: 1 candidate
mux_ts debug: shaping=200000 pcr=70000 dts_delay=400000
main debug: using sout mux module "mux_ts"
main debug: TIMER module_need() : 53,000 ms - Total 53,000 ms / 1 intvls (Avg 53,000 ms)
main debug: muxer support adding stream at any time
stream_out_rtp debug: maximum RTP packet size: 1400 bytes
main debug: net: connecting to [192.168.0.113]:5004
main debug: net: connecting to [192.168.0.113]:5005 from [192.168.0.113]:3364
main debug: thread (RTP send thread) created at priority 15 (../../.././modules/stream_out/rtp.c:1279)
main debug: thread started
stream_out_rtp debug: sdp=
v=0

o=- 14862169196682280960 14862169196682280960 IN IP4 fredt60

s=Unnamed

i=N/A

c=IN IP4 192.168.0.113

t=0 0

a=tool:vlc 1.0.1

a=recvonly

a=type:broadcast

a=charset:UTF-8

m=video 5004 RTP/AVP 33

b=RR:0

a=rtpmap:33 MP2T/90000


main debug: using sout stream module "stream_out_rtp"
main debug: TIMER module_need() : 107,000 ms - Total 107,000 ms / 1 intvls (Avg 107,000 ms)
main debug: set config option: sout-transcode-width to 480
main debug: set config option: sout-transcode-height to 270
main debug: set config option: sout-transcode-threads to 32
main debug: set config option: sout-transcode-vcodec to x264
main debug: set config option: sout-transcode-venc to x264{8x8dct=0,aq-mode=2,aq-strength=1.0,asm=1,aud=0,bframes=0,bpyramid=0,b-adapt=1,b-bias=0,cabac=1,chroma-me=1,chroma-qp-offset=0,cplxblur=20.0,crf=0,dct-decimate=1,deadzone-inter=21,deadzone-intra=11,deblock=0:0,direct=spatial,direct-8x8=-1,fast-pskip=1,interlaced=0,ipratio=1.40,keyint=250,level=5.1,me=hex,merange=16,min-keyint=25,mixed-refs=0,mvrange=-1,mvrange-thread=-1,nf=0,non-deterministic=0,nr=0,partitions=normal,pass=0,pbratio=1.30,psnr=0,qblur=0.5,qp=26,qcomp=0,qpstep=4,qpmax=51,qpmin=10,quiet=0,ratetol=0,ref=1,scenecut=40,sps-id=0,ssim=0,stats=x264_2pass.log,subme=5,trellis=0,verbose=0,vbv-bufsize=0,vbv-init=0.9,vbv-maxrate=1000,weightb=0}
main debug: set config option: sout-transcode-vb to 500
main debug: set config option: sout-transcode-scale to 0.7
main debug: set config option: sout-transcode-fps to 15
main debug: set config option: sout-transcode-audio-sync to (null)
main debug: set config option: sout-transcode-acodec to mp4a
main debug: set config option: sout-transcode-ab to 64
main debug: set config option: sout-transcode-channels to 2
main debug: set config option: sout-transcode-samplerate to 44100
stream_out_transcode debug: codec audio=mp4a 44100Hz 2 channels 64Kb/s
stream_out_transcode debug: codec video=x264 480x270 scaling: 0,000000 500kb/s
main debug: using sout stream module "stream_out_transcode"
main debug: TIMER module_need() : 131,000 ms - Total 131,000 ms / 1 intvls (Avg 131,000 ms)
main debug: using sout stream module "stream_out_duplicate"
main debug: TIMER module_need() : 147,000 ms - Total 147,000 ms / 1 intvls (Avg 147,000 ms)
main debug: using timeshift granularity of 50 MBytes
main debug: using timeshift path 'C:\DOCUME~1\fred\LOCALS~1\Temp'
main debug: `dshow://' gives access `dshow' demux `' path `'
main debug: creating demux: access='dshow' demux='' path=''
main debug: looking for access_demux module: 1 candidate
dshow debug: dshow-vdev: 
dshow debug: dshow-adev: 
dshow debug: found device: Logitech QuickCam Communicate STX
dshow debug: asking for default device: Logitech QuickCam Communicate STX
dshow debug: asked for Logitech QuickCam Communicate STX, binding to Logitech QuickCam Communicate STX
dshow debug: using device: Logitech QuickCam Communicate STX
dshow debug: EnumDeviceCaps: output pin: Capturer
dshow debug: EnumDeviceCaps: output pin: Fixe
dshow debug: EnumDeviceCaps: trying pin Capturer
dshow debug: EnumDeviceCaps: input pin default format configured
dshow debug: EnumDeviceCaps: input pin accepts chroma: I420, width:640, height:480, fps:15,000015
dshow debug: CaptureFilter::JoinFilterGraph
dshow debug: connecting filters
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::ConnectedTo [not connected]
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::QueryFilterInfo
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryAccept [OK] (width=640, height=480, chroma=I420, fps=15,000015)
dshow debug: CapturePin::ReceiveConnection [OK]
dshow debug: CapturePin::NotifyAllocator
dshow debug: ConnectFilters: graph_builder2 available.
dshow warning: ConnectFilters: No crossBar routes found (incobatible pin types)
dshow debug: filters connected successfully !
dshow debug: MEDIATYPE_Video
dshow debug: selected video pin accepts format: I420
dshow debug: found device: Audio Bluetooth
dshow debug: found device: Audio de haute qualitÃ© Bluetoot
dshow debug: found device: Logitech Mic (Communicate STX)
dshow debug: found device: SoundMAX HD Audio
dshow debug: asking for default device: Audio Bluetooth
dshow debug: asked for Audio Bluetooth, binding to Audio Bluetooth
dshow debug: using device: Audio Bluetooth
dshow debug: EnumDeviceCaps: output pin: Capture
dshow debug: EnumDeviceCaps: input pin: Entr‚e ligne
dshow debug: EnumDeviceCaps: input pin: Microphone
dshow debug: EnumDeviceCaps: trying pin Capture
dshow debug: EnumDeviceCaps: input pin default format configured
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:44100 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:44100 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:44100 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:32000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:32000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:22050 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:22050 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:11025 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:11025 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:8000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:8000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:44100 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:44100 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:22050 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:22050 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:11025 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:11025 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:8000 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:8000 bits/sample:8
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:48000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:48000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:2, samples/sec:96000 bits/sample:16
dshow debug: EnumDeviceCaps: input pin accepts format: araw, channels:1, samples/sec:96000 bits/sample:16
dshow debug: CaptureFilter::JoinFilterGraph
dshow debug: connecting filters
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::ConnectedTo [not connected]
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::QueryFilterInfo
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryAccept [OK] (channels=2, samples/sec=44100, bits/samples=16, format=araw)
dshow debug: CapturePin::ReceiveConnection [OK]
dshow debug: CapturePin::NotifyAllocator
dshow debug: filters connected successfully !
dshow debug: MEDIATYPE_Audio
dshow debug: selected audio pin accepts format: araw
dshow debug: Playing...
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::QueryPinInfo
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::ConnectedTo [OK]
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::ConnectedTo [OK]
dshow debug: CaptureFilter::SetSyncSource
dshow debug: CaptureFilter::SetSyncSource
dshow debug: CaptureFilter::Pause
dshow debug: CaptureFilter::Pause
dshow debug: CaptureFilter::Run
dshow debug: CaptureFilter::Run
main debug: selecting program id=0
main debug: using access_demux module "dshow"
main debug: TIMER module_need() : 1384,000 ms - Total 1384,000 ms / 1 intvls (Avg 1384,000 ms)
main debug: looking for a subtitle file in C:\_cpp\Projects\Webvox_Encoder\Webvox_Encoder\Webvox_Encoder\bin\Release\
main debug: looking for packetizer module: 21 candidates
main debug: using packetizer module "rawvideo"
main debug: TIMER module_need() : 223,000 ms - Total 223,000 ms / 1 intvls (Avg 223,000 ms)
main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315)
main debug: thread started
main debug: looking for packetizer module: 21 candidates
main debug: using packetizer module "packetizer_copy"
main debug: TIMER module_need() : 345,000 ms - Total 345,000 ms / 1 intvls (Avg 345,000 ms)
main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315)
main debug: thread started
main debug: starting in async mode
main debug: `dshow://' successfully opened
main debug: Buffering 0%
main debug: switching to sync mode
main debug: Buffering -2%
main debug: adding a new sout input (sout_input:0x54d2cc0)
stream_out_duplicate debug: duplicated a new stream codec=s16l (es=1 group=0)
main debug: looking for decoder module: 36 candidates
main debug: Buffering -4%
main error: Invalid PCR value in ES_OUT_SET_(GROUP_)PCR !
main debug: Buffering 3%
main debug: adding a new sout input (sout_input:0x54deeb0)
main debug: Buffering 5%
main debug: Buffering 2%
main debug: Buffering 6%
main debug: Buffering 7%
araw debug: samplerate:44100Hz channels:2 bits/sample:16
main debug: using decoder module "araw"
main debug: TIMER module_need() : 244,000 ms - Total 244,000 ms / 1 intvls (Avg 244,000 ms)
main debug: Buffering 8%
main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315)
stream_out_duplicate debug:     - added for output 0
stream_out_transcode debug: creating audio transcoding from fcc=`s16l' to fcc=`mp4a'
main debug: looking for decoder module: 36 candidates
araw debug: samplerate:44100Hz channels:2 bits/sample:16
main debug: using decoder module "araw"
main debug: TIMER module_need() : 1,000 ms - Total 1,000 ms / 1 intvls (Avg 1,000 ms)
main debug: looking for encoder module: 12 candidates
main debug: thread started
main debug: Buffering 9%
main debug: Buffering 9%
main debug: Buffering 11%
main debug: Buffering 12%
main debug: Buffering 14%
main debug: Buffering 14%
main debug: Buffering 15%
main debug: Buffering 16%
avcodec debug: libavcodec initialized (interface 0x342000)
avcodec debug: found encoder MPEG AAC Audio
main debug: using encoder module "avcodec"
main debug: TIMER module_need() : 440,000 ms - Total 440,000 ms / 1 intvls (Avg 440,000 ms)
main debug: adding a new input
mux_ts debug: adding input codec=mp4a pid=68
mux_ts debug: new PCR PID is 68
stream_out_duplicate debug:     - added for output 1
stream_out_duplicate debug: duplicated a new stream codec=I420 (es=0 group=0)
main debug: looking for decoder module: 36 candidates
main debug: Buffering 17%
main debug: Buffering 18%
main debug: Buffering 19%
main debug: Buffering 20%
main debug: Buffering 21%
main debug: Buffering 22%
main debug: Buffering 23%
main debug: Buffering 24%
main debug: using decoder module "rawvideo"
main debug: TIMER module_need() : 439,000 ms - Total 439,000 ms / 1 intvls (Avg 439,000 ms)
main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315)
main debug: thread started
stream_out_duplicate debug:     - added for output 0
stream_out_transcode debug: creating video transcoding from fcc=`I420' to fcc=`x264'
main debug: looking for decoder module: 36 candidates
main debug: using decoder module "rawvideo"
main debug: TIMER module_need() : 1,000 ms - Total 1,000 ms / 1 intvls (Avg 1,000 ms)
main debug: looking for encoder module: 1 candidate
x264 debug: version x264 0.68.X
main debug: set config option: sout-x264-8x8dct to 0
main debug: set config option: sout-x264-aq-mode to 2
main debug: set config option: sout-x264-aq-strength to 1.0
main debug: set config option: sout-x264-asm to 1
main debug: set config option: sout-x264-aud to 0
main debug: set config option: sout-x264-bframes to 0
main debug: set config option: sout-x264-bpyramid to 0
main debug: set config option: sout-x264-b-adapt to 1
main debug: set config option: sout-x264-b-bias to 0
main debug: set config option: sout-x264-cabac to 1
main debug: set config option: sout-x264-chroma-me to 1
main debug: set config option: sout-x264-chroma-qp-offset to 0
main debug: set config option: sout-x264-cplxblur to 20.0
main debug: set config option: sout-x264-crf to 0
main debug: Buffering 25%
main debug: set config option: sout-x264-dct-decimate to 1
main debug: set config option: sout-x264-deadzone-inter to 21
main debug: set config option: sout-x264-deadzone-intra to 11
main debug: set config option: sout-x264-deblock to 0:0
main debug: set config option: sout-x264-direct to spatial
main debug: set config option: sout-x264-direct-8x8 to -1
main debug: set config option: sout-x264-fast-pskip to 1
main debug: set config option: sout-x264-interlaced to 0
main debug: set config option: sout-x264-ipratio to 1.40
main debug: set config option: sout-x264-keyint to 250
main debug: set config option: sout-x264-level to 5.1
main debug: set config option: sout-x264-me to hex
main debug: set config option: sout-x264-merange to 16
main debug: set config option: sout-x264-min-keyint to 25
main debug: set config option: sout-x264-mixed-refs to 0
main debug: set config option: sout-x264-mvrange to -1
main debug: set config option: sout-x264-mvrange-thread to -1
main debug: set config option: sout-x264-nf to 0
main debug: set config option: sout-x264-non-deterministic to 0
main debug: set config option: sout-x264-nr to 0
main debug: set config option: sout-x264-partitions to normal
main debug: set config option: sout-x264-pass to 0
main debug: set config option: sout-x264-pbratio to 1.30
main debug: set config option: sout-x264-psnr to 0
main debug: set config option: sout-x264-qblur to 0.5
main debug: set config option: sout-x264-qp to 26
main debug: set config option: sout-x264-qcomp to 0
main debug: set config option: sout-x264-qpstep to 4
main debug: set config option: sout-x264-qpmax to 51
main debug: set config option: sout-x264-qpmin to 10
main debug: set config option: sout-x264-quiet to 0
main debug: set config option: sout-x264-ratetol to 0
main debug: set config option: sout-x264-ref to 1
main debug: set config option: sout-x264-scenecut to 40
main debug: set config option: sout-x264-sps-id to 0
main debug: set config option: sout-x264-ssim to 0
main debug: set config option: sout-x264-stats to x264_2pass.log
main debug: set config option: sout-x264-subme to 5
main debug: set config option: sout-x264-trellis to 0
main debug: set config option: sout-x264-verbose to 0
main debug: set config option: sout-x264-vbv-bufsize to 0
main debug: set config option: sout-x264-vbv-init to 0.9
main debug: set config option: sout-x264-vbv-maxrate to 1000
main debug: set config option: sout-x264-weightb to 0
main debug: Buffering 27%
x264 debug: initializing pthread-win32
main debug: using encoder module "x264"
main debug: TIMER module_need() : 111,000 ms - Total 111,000 ms / 1 intvls (Avg 111,000 ms)
x264 debug: pthread-win32 deinitialized
main debug: removing module "x264"
main debug: thread (encoder) created at priority 0 (../../.././modules/stream_out/transcode.c:1546)
stream_out_duplicate debug:     - added for output 1
main debug: thread started
main debug: Buffering 29%
main debug: Buffering 30%
main debug: Buffering 31%
main debug: Buffering 31%
main debug: Buffering 33%
main debug: Buffering 33%
main debug: Buffering 35%
main debug: Buffering 35%
main debug: Buffering 38%
main debug: Buffering 37%
main debug: Buffering 39%
main debug: Buffering 40%
main debug: Buffering 41%
main debug: Buffering 42%
main debug: Buffering 44%
main debug: Buffering 44%
main debug: Buffering 46%
main debug: Buffering 46%
main debug: Buffering 47%
main debug: Buffering 48%
main debug: Buffering 49%
main debug: Buffering 51%
main debug: Buffering 51%
main debug: Buffering 53%
main debug: Buffering 53%
main debug: Buffering 55%
main debug: Buffering 55%
main debug: Buffering 57%
main debug: Buffering 57%
main debug: Buffering 59%
main debug: Buffering 60%
main debug: Buffering 61%
main debug: Buffering 62%
main debug: Buffering 63%
main debug: Buffering 64%
main debug: Buffering 65%
main debug: Buffering 66%
main debug: Buffering 67%
main debug: Buffering 68%
main debug: Buffering 69%
main debug: Buffering 70%
main debug: Buffering 71%
main debug: Buffering 72%
main debug: Buffering 73%
main debug: Buffering 74%
main debug: Buffering 76%
main debug: Buffering 77%
main debug: Buffering 78%
main debug: Buffering 79%
main debug: Buffering 79%
main debug: Buffering 81%
main debug: Buffering 81%
main debug: Buffering 83%
main debug: Buffering 83%
main debug: Buffering 85%
main debug: Buffering 85%
main debug: Buffering 87%
main debug: Buffering 88%
main debug: Buffering 90%
main debug: Buffering 89%
main debug: Buffering 92%
main debug: Buffering 94%
main debug: Buffering 94%
main debug: Buffering 95%
main debug: Buffering 96%
main debug: Buffering 97%
main debug: Buffering 98%
main debug: Buffering 99%
main debug: Stream buffering done (5057 ms in 4900 ms)
main debug: Decoder buffering done in 0 ms
stream_out_transcode debug: drift is too high, resetting master sync
main debug: creating aout
main debug: looking for audio output module: 3 candidates
main debug: no usable vout present, spawning one
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: decoder aspect is 576000:432000
stream_out_transcode debug: source pixel aspect is 1,000000:1
stream_out_transcode debug: scaled pixel aspect is 0,750000:1
stream_out_transcode debug: source 640x480, destination 480x270
stream_out_transcode debug: encoder aspect is 576000:432000
main debug: looking for video filter2 module: 20 candidates
main debug: looking for text renderer module: 2 candidates
aout_directx debug: OpenAudio
main debug: thread (fontlist builder) created at priority 0 (../../.././modules/misc/freetype.c:473)
swscale debug: 640x480 chroma: I420 -> 480x270 chroma: I420 with scaling using Bicubic (good quality)
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 318,000 ms - Total 318,000 ms / 1 intvls (Avg 318,000 ms)
freetype debug: using fontsize: 2
main debug: Filter 'swscale' (0x5493db4) appended to chain
main debug: thread started
main debug: using text renderer module "freetype"
main debug: TIMER module_need() : 299,000 ms - Total 299,000 ms / 1 intvls (Avg 299,000 ms)
main debug: looking for video filter2 module: 20 candidates
stream_out_transcode debug: destination (after video filters) 480x270
swscale debug: 32x32 chroma: YUVA -> 16x16 chroma: YUVA with scaling using Bicubic (good quality)
main debug: looking for encoder module: 1 candidate
main debug: using video filter2 module "swscale"
main debug: TIMER module_need() : 2,000 ms - Total 2,000 ms / 1 intvls (Avg 2,000 ms)
main debug: looking for video filter2 module: 20 candidates
x264 debug: version x264 0.68.X
main debug: set config option: sout-x264-8x8dct to 0
main debug: set config option: sout-x264-aq-mode to 2
main debug: set config option: sout-x264-aq-strength to 1.0
main debug: set config option: sout-x264-asm to 1
main debug: set config option: sout-x264-aud to 0
main debug: set config option: sout-x264-bframes to 0
main debug: set config option: sout-x264-bpyramid to 0
main debug: set config option: sout-x264-b-adapt to 1
main debug: set config option: sout-x264-b-bias to 0
main debug: set config option: sout-x264-cabac to 1
main debug: set config option: sout-x264-chroma-me to 1
main debug: set config option: sout-x264-chroma-qp-offset to 0
main debug: set config option: sout-x264-cplxblur to 20.0
main debug: set config option: sout-x264-crf to 0
main debug: set config option: sout-x264-dct-decimate to 1
main debug: set config option: sout-x264-deadzone-inter to 21
main debug: set config option: sout-x264-deadzone-intra to 11
main debug: set config option: sout-x264-deblock to 0:0
main debug: set config option: sout-x264-direct to spatial
main debug: set config option: sout-x264-direct-8x8 to -1
main debug: set config option: sout-x264-fast-pskip to 1
main debug: set config option: sout-x264-interlaced to 0
main debug: set config option: sout-x264-ipratio to 1.40
main debug: set config option: sout-x264-keyint to 250
main debug: set config option: sout-x264-level to 5.1
main debug: set config option: sout-x264-me to hex
main debug: set config option: sout-x264-merange to 16
main debug: set config option: sout-x264-min-keyint to 25
main debug: set config option: sout-x264-mixed-refs to 0
main debug: set config option: sout-x264-mvrange to -1
main debug: set config option: sout-x264-mvrange-thread to -1
main debug: set config option: sout-x264-nf to 0
main debug: set config option: sout-x264-non-deterministic to 0
main debug: set config option: sout-x264-nr to 0
main debug: set config option: sout-x264-partitions to normal
main debug: set config option: sout-x264-pass to 0
main debug: set config option: sout-x264-pbratio to 1.30
main debug: set config option: sout-x264-psnr to 0
main debug: set config option: sout-x264-qblur to 0.5
main debug: set config option: sout-x264-qp to 26
main debug: set config option: sout-x264-qcomp to 0
main debug: set config option: sout-x264-qpstep to 4
main debug: set config option: sout-x264-qpmax to 51
main debug: set config option: sout-x264-qpmin to 10
main debug: set config option: sout-x264-quiet to 0
main debug: set config option: sout-x264-ratetol to 0
main debug: set config option: sout-x264-ref to 1
main debug: set config option: sout-x264-scenecut to 40
main debug: set config option: sout-x264-sps-id to 0
main debug: set config option: sout-x264-ssim to 0
main debug: set config option: sout-x264-stats to x264_2pass.log
main debug: set config option: sout-x264-subme to 5
main debug: set config option: sout-x264-trellis to 0
main debug: set config option: sout-x264-verbose to 0
main debug: set config option: sout-x264-vbv-bufsize to 0
main debug: set config option: sout-x264-vbv-init to 0.9
main debug: set config option: sout-x264-vbv-maxrate to 1000
main debug: set config option: sout-x264-weightb to 0
x264 debug: initializing pthread-win32
freetype debug: Building font database...
freetype debug: Finished building font database.
freetype debug: Took 1000 microseconds
main debug: thread ended
main debug: using encoder module "x264"
main debug: TIMER module_need() : 73,000 ms - Total 73,000 ms / 1 intvls (Avg 73,000 ms)
main debug: adding a new input
mux_ts debug: adding input codec=h264 pid=69
mux_ts debug: new PCR PID is 69
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (615962)
packetizer_copy debug: need dts > 0
stream_out_transcode debug: late picture skipped (289045)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (709706)
stream_out_transcode debug: late picture skipped (227137)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (372450)
stream_out_transcode debug: late picture skipped (132036)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (137413)
main warning: late buffer for mux input (21314)
stream_out_transcode debug: late picture skipped (73245)
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
aout_directx debug: found device: Périphérique audio principal
aout_directx debug: found device: SoundMAX HD Audio
aout_directx debug: found device: Audio Bluetooth
aout_directx debug: found device: Audio de haute qualité Bluetooth
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (543083)
mux_ts warning: packet with too strange dts (dts=2947771038,old=2947919220,pcr=2948052917)
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (68069)
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (135586)
aout_directx debug: device supports 2 channels (DEFAULT!)
aout_directx debug: device supports 1 channel
aout_directx debug: Windows says your SpeakerConfig is stereo
aout_directx debug: creating DirectSoundThread
main debug: thread (DirectSound Notification Thread) created at priority 15 (../../.././modules/audio_output/directx.c:427)
main debug: using audio output module "aout_directx"
main debug: TIMER module_need() : 882,000 ms - Total 882,000 ms / 1 intvls (Avg 882,000 ms)
main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'fl32'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: thread started
aout_directx debug: DirectSoundThread ready
main warning: late buffer for mux input (200103)
mux_ts warning: packet with too strange dts (dts=2947679294,old=2947794257,pcr=2948500966)
stream_out_transcode debug: drift is too high, resetting master sync
main warning: late buffer for mux input (29363)
mux_ts debug: adjusting rate at -1087193/287904 (3/408)
yuvp debug: YUVP to YUVA converter
main debug: using video filter2 module "yuvp"
main debug: TIMER module_need() : 738,000 ms - Total 738,000 ms / 1 intvls (Avg 738,000 ms)
main debug: new aspect-ratio 16:9, sample aspect-ratio 4:3
main debug: window size: 853x480
main debug: cropping picture 640x480 to 0,0,640x480
main debug: window size: 853x480
main debug: looking for video output module: 7 candidates
stream_out_transcode debug: drift is too high, resetting master sync
vout_directx debug: creating DirectXEventThread
main debug: thread started
vout_directx debug: DirectXCreateWindow
main debug: looking for hwnd module: 3 candidates
main debug: thread (Vout Events Thread) created at priority 0 (../../../.././modules/video_output/msw/directx.c:282)
main debug: using audio filter module "converter_float"
main debug: using hwnd module "drawable"
main debug: TIMER module_need() : 42,000 ms - Total 42,000 ms / 1 intvls (Avg 42,000 ms)
main debug: TIMER module_need() : 498,000 ms - Total 498,000 ms / 1 intvls (Avg 498,000 ms)
main debug: found a filter for the whole conversion
main debug: looking for audio mixer module: 3 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 23,000 ms - Total 23,000 ms / 1 intvls (Avg 23,000 ms)
main debug: input 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
vout_directx debug: created video sub-window
main debug: looking for audio filter module: 1 candidate
vout_directx debug: Vout EventThread running
vout_directx debug: DirectXInitDDraw
scaletempo warning: bad input or output format
main warning: no audio filter module matching "scaletempo" could be loaded
main debug: TIMER module_need() : 50,000 ms - Total 50,000 ms / 1 intvls (Avg 50,000 ms)
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 44100 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0,200 overlap, 14 search
scaletempo debug: 1,000 scale, 1323,000 stride_in, 1323 stride_out, 1059 standing, 264 overlap, 617 search, 2204 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 1,000 ms - Total 1,000 ms / 1 intvls (Avg 1,000 ms)
main debug: filter(s) 's16l'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
vout_directx debug: directx-device: 
vout_directx debug: DirectXEnumCallback: Pilote d'affichage principal, display
main debug: using audio filter module "converter_float"
main debug: TIMER module_need() : 62,000 ms - Total 62,000 ms / 1 intvls (Avg 62,000 ms)
main debug: found a filter for the whole conversion
main debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: using audio filter module "bandlimited_resampler"
main debug: TIMER module_need() : 1,000 ms - Total 1,000 ms / 1 intvls (Avg 1,000 ms)
main debug: found a filter for the whole conversion
main warning: PTS is out of range (1415000), dropping buffer
main warning: PTS is out of range (1392781), dropping buffer
main warning: PTS is out of range (1369561), dropping buffer
main warning: PTS is out of range (1347341), dropping buffer
main warning: PTS is out of range (1324121), dropping buffer
main warning: PTS is out of range (1526000), dropping buffer
main warning: PTS is out of range (1502781), dropping buffer
main warning: PTS is out of range (1480561), dropping buffer
main warning: PTS is out of range (1457341), dropping buffer
main warning: PTS is out of range (1434121), dropping buffer
vout_directx debug: DirectXEnumCallback: ATI Mobility Radeon X1400, \\.\DISPLAY1
main warning: PTS is out of range (1626000), dropping buffer
main warning: PTS is out of range (1602781), dropping buffer
main warning: PTS is out of range (1579561), dropping buffer
main warning: PTS is out of range (1557341), dropping buffer
main warning: PTS is out of range (1534121), dropping buffer
main warning: PTS is out of range (1295000), dropping buffer
main warning: PTS is out of range (1271781), dropping buffer
main warning: PTS is out of range (1248561), dropping buffer
main warning: PTS is out of range (1226341), dropping buffer
vout_directx debug: DirectXEnumCallback: ATI Mobility Radeon X1400, \\.\DISPLAY2
main warning: PTS is out of range (1203121), dropping buffer
vout_directx debug: selecting ATI Mobility Radeon X1400, \\.\DISPLAY2
main warning: PTS is out of range (1048000), dropping buffer
main warning: PTS is out of range (1025781), dropping buffer
main warning: PTS is out of range (1002561), dropping buffer
main warning: PTS is out of range (979341), dropping buffer
main warning: PTS is out of range (956121), dropping buffer
main warning: PTS is out of range (946000), dropping buffer
main warning: PTS is out of range (922781), dropping buffer
main warning: PTS is out of range (900561), dropping buffer
main warning: PTS is out of range (877341), dropping buffer
vout_directx debug: screen dimensions (-1680x0,0x1050)
main warning: PTS is out of range (855121), dropping buffer
main warning: PTS is out of range (846000), dropping buffer
vout_directx debug: DirectDraw Capabilities: overlay=1 yuvoverlay=1 can_deinterlace_overlay=1 colorkey=1 stretch=1 bltfourcc=1
vout_directx debug: End DirectXInitDDraw
main warning: PTS is out of range (823781), dropping buffer
vout_directx debug: DirectXCreateDisplay
main warning: PTS is out of range (800561), dropping buffer
main warning: PTS is out of range (778341), dropping buffer
vout_directx debug: DirectXCreateClipper
main warning: PTS is out of range (763121), dropping buffer
main warning: PTS is out of range (749000), dropping buffer
main warning: PTS is out of range (725781), dropping buffer
main warning: PTS is out of range (702561), dropping buffer
main warning: PTS is out of range (679341), dropping buffer
main warning: PTS is out of range (656121), dropping buffer
main warning: PTS is out of range (703000), dropping buffer
main warning: PTS is out of range (680781), dropping buffer
main warning: PTS is out of range (657561), dropping buffer
main warning: PTS is out of range (634341), dropping buffer
main warning: PTS is out of range (611121), dropping buffer
main warning: PTS is out of range (595000), dropping buffer
main warning: PTS is out of range (571781), dropping buffer
main warning: PTS is out of range (548561), dropping buffer
main warning: PTS is out of range (525341), dropping buffer
main warning: PTS is out of range (502121), dropping buffer
main warning: PTS is out of range (487000), dropping buffer
main warning: PTS is out of range (463781), dropping buffer
main warning: PTS is out of range (440561), dropping buffer
main warning: PTS is out of range (421341), dropping buffer
main warning: PTS is out of range (398121), dropping buffer
main warning: PTS is out of range (391000), dropping buffer
main warning: PTS is out of range (367781), dropping buffer
main warning: PTS is out of range (345561), dropping buffer
main warning: PTS is out of range (322341), dropping buffer
main warning: PTS is out of range (299121), dropping buffer
main warning: PTS is out of range (281000), dropping buffer
main warning: PTS is out of range (257781), dropping buffer
main warning: PTS is out of range (235561), dropping buffer
main warning: PTS is out of range (212341), dropping buffer
main warning: PTS is out of range (189121), dropping buffer
main warning: PTS is out of range (173000), dropping buffer
main warning: PTS is out of range (149781), dropping buffer
main warning: PTS is out of range (127561), dropping buffer
main warning: PTS is out of range (104341), dropping buffer
main warning: PTS is out of range (81121), dropping buffer
main warning: PTS is out of range (64000), dropping buffer
main warning: PTS is out of range (40781), dropping buffer
stream_out_transcode debug: drift is too high, resetting master sync
main warning: PTS is out of range (18561), dropping buffer
main warning: PTS is out of range (3341), dropping buffer
main warning: PTS is out of range (40121), dropping buffer
main warning: PTS is out of range (22000), dropping buffer
main warning: PTS is out of range (-1219), dropping buffer
main warning: PTS is out of range (-23439), dropping buffer
vout_directx debug: disabling screen saver
main debug: using video output module "vout_directx"
main debug: TIMER module_need() : 569,000 ms - Total 569,000 ms / 1 intvls (Avg 569,000 ms)
main debug: Deinterlacing available
vout_directx debug: NewPictureVec overlay:yes chroma:YV12
vout_directx debug: YUV overlay created successfully
vout_directx debug: End NewPictureVec (succeeded)
main debug: got 1 direct buffer(s)
main debug: pic render sz 640x480, of (0,0), vsz 640x480, 4cc I420, ar 16:9, sar 1:1, msk r0x0 g0x0 b0x0
main debug: pic in sz 640x480, of (0,0), vsz 640x480, 4cc I420, ar 16:9, sar 4:3, msk r0x0 g0x0 b0x0
main debug: pic out sz 640x480, of (0,0), vsz 640x480, 4cc I420, ar 16:9, sar 4:3, msk r0x0 g0x0 b0x0
main debug: direct render, mapping render pictures 0-14 to system pictures 1-15
main warning: late picture skipped (1618084 > -10)
main warning: late picture skipped (1523045 > -10)
main warning: late picture skipped (1459137 > -10)
main warning: late picture skipped (1301245 > -10)
main warning: late picture skipped (1173035 > -10)
main warning: late picture skipped (1076165 > -10)
main warning: late picture skipped (1365036 > -10)
main warning: late picture skipped (885295 > -10)
main warning: late picture skipped (981398 > -10)
main warning: late picture skipped (790275 > -10)
main warning: late picture skipped (694275 > -10)
main warning: late picture skipped (599391 > -10)
main warning: late picture skipped (504269 > -10)
main warning: late picture skipped (281142 > -10)
main warning: late picture skipped (185385 > -10)
main warning: late picture skipped (90874 > -10)
freetype debug: using fontsize: 30
stream_out_transcode debug: drift is too high, resetting master sync
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() : 56,000 ms - Total 56,000 ms / 1 intvls (Avg 56,000 ms)
main warning: late picture skipped (18252 > -29007)
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
mux_ts debug: adjusting rate at 34482/608330 (452/324)
mux_ts debug: adjusting rate at 157529/573848 (324/0)
stream_out_transcode debug: drift is too high, resetting master sync
mux_ts warning: packet with too strange dts (dts=2955798014,old=2955894262,pcr=2955894262)
mux_ts debug: adjusting rate at 0/383755 (215/58)
mux_ts debug: adjusting rate at 0/383755 (50/8)
mux_ts debug: adjusting rate at 0/383755 (8/0)
stream_out_transcode debug: drift is too high, resetting master sync
mux_ts debug: adjusting rate at -297989/320406 (3/1251)
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main warning: buffer is 58295 in advance, triggering downsampling
main warning: timing screwed, stopping resampling
main warning: buffer is 101428 in advance, triggering downsampling
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main warning: audio drift is too big (-225054), clearing out
main warning: timing screwed, stopping resampling
main warning: mixer start isn't output start (-94670)
stream_out_transcode debug: drift is too high, resetting master sync
main debug: audio output is starving (287486), playing silence
main warning: audio drift is too big (336024), dropping buffer
main warning: audio drift is too big (312805), dropping buffer
main warning: audio drift is too big (289585), dropping buffer
main warning: audio drift is too big (266365), dropping buffer
main warning: audio drift is too big (243145), dropping buffer
main warning: buffer is 109024 late, triggering upsampling
main warning: audio drift is too big (126686), dropping buffer
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
stream_out_transcode debug: drift is too high, resetting master sync
main debug: control type=0
main debug: control: stopping input
main debug: thread times: real 0m16,296875s, kernel 0m0,015625s, user 0m0,562500s
main debug: removing module "rawvideo"
main debug: killing decoder fourcc `I420', 0 PES in FIFO
main debug: removing a sout input (sout_input:0x54deeb0)
main warning: can't get output picture
main debug: thread times: real 0m14,828125s, kernel 0m0,046875s, user 0m0,031250s
main debug: removing module "rawvideo"
main debug: killing decoder fourcc `I420', 36 PES in FIFO
main debug: [0] 2 0
main debug: [1] 2 0
main debug: [2] 2 0
main debug: [3] 2 0
main debug: [4] 4 0
main debug: [5] 2 0
main debug: [6] 2 0
main debug: [7] 2 0
main debug: [8] 2 0
main debug: [9] 2 0
main debug: [10] 2 0
main debug: [11] 2 0
main debug: [12] 2 0
main debug: [13] 2 0
main debug: [14] 2 0
main debug: saving a free vout
main debug: thread ended
main debug: thread times: real 0m14,718750s, kernel 0m0,031250s, user 0m0,359375s
main debug: TIMER encoding video frame : 2,000 ms - Total 2499,000 ms / 242 intvls (Avg 10,326 ms)
main debug: removing module "rawvideo"
x264 debug: pthread-win32 deinitialized
main debug: removing module "x264"
main debug: Filter 'swscale' (0x5493db4) removed from chain
main debug: removing module "swscale"
mux_ts debug: removing input pid=69
mux_ts debug: new PCR PID is 68
main debug: thread times: real 0m15,984375s, kernel 0m0,031250s, user 0m0,109375s
main debug: removing module "packetizer_copy"
main debug: killing decoder fourcc `araw', 0 PES in FIFO
main debug: removing a sout input (sout_input:0x54d2cc0)
main warning: received buffer in the future
main debug: thread times: real 0m15,734375s, kernel 0m0,062500s, user 0m0,046875s
main debug: removing module "araw"
main debug: killing decoder fourcc `s16l', 41 PES in FIFO
main debug: removing module "converter_float"
main debug: removing module "scaletempo"
main debug: removing module "bandlimited_resampler"
aout_directx debug: closing audio device
aout_directx debug: DirectSoundThread exiting
main debug: thread ended
main debug: thread times: real 0m10,203125s, kernel 0m0,015625s, user 0m0,000000s
main debug: removing module "aout_directx"
main debug: removing module "converter_float"
main debug: removing module "float32_mixer"
main debug: releasing aout
main debug: TIMER encoding audio frame : 0,000 ms - Total 83,000 ms / 740 intvls (Avg 0,112 ms)
main debug: removing module "araw"
main debug: removing module "avcodec"
mux_ts debug: removing input pid=68
mux_ts debug: new PCR PID is 8191
main warning: no more input streams for this mux
dshow debug: CaptureFilter::Pause
dshow debug: CaptureFilter::Pause
dshow debug: CaptureFilter::Stop
dshow debug: CapturePin::EndFlush
dshow debug: CaptureFilter::Stop
dshow debug: CapturePin::EndFlush
dshow debug: releasing DirectShow
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::ConnectedTo [OK]
dshow debug: CapturePin::Disconnect [OK]
dshow debug: CaptureFilter::SetSyncSource
dshow debug: CaptureFilter::JoinFilterGraph
dshow debug: CaptureFilter::GetState 0
dshow debug: CaptureFilter::~CaptureFilter
dshow debug: CapturePin::~CapturePin
dshow debug: CaptureFilter::EnumPins
dshow debug: CapturePin::QueryDirection
dshow debug: CapturePin::ConnectedTo [OK]
dshow debug: CapturePin::Disconnect [OK]
dshow debug: CaptureFilter::SetSyncSource
dshow debug: CaptureFilter::JoinFilterGraph
dshow debug: CaptureFilter::GetState 0
dshow debug: CaptureFilter::~CaptureFilter
dshow debug: CapturePin::~CapturePin
main debug: removing module "dshow"
main debug: Program doesn't contain anymore ES
main debug: thread ended
main debug: thread times: real 0m18,546875s, kernel 0m0,093750s, user 0m0,171875s
main debug: TIMER input launching for 'dshow://' : 2176,000 ms - Total 2176,000 ms / 1 intvls (Avg 2176,000 ms)
main debug: destroying chain... (name=duplicate)
stream_out_duplicate debug: closing a duplication
main debug: destroying chain... (name=display)
main debug: removing module "stream_out_display"
main debug: destroying chain done
main debug: destroying chain... (name=transcode)
main debug: destroying chain... (name=rtp)
main debug: removing module "mux_ts"
main debug: thread times: real 0m18,406250s, kernel 0m0,687500s, user 0m0,500000s
main debug: removing module "stream_out_rtp"
main debug: destroying chain done
main debug: removing module "stream_out_transcode"
main debug: destroying chain done
main debug: removing module "stream_out_duplicate"
main debug: destroying chain done
vout_directx debug: DirectXCloseSurface
vout_directx debug: DirectXCloseDisplay
vout_directx debug: DirectXCloseDisplay clipper
vout_directx debug: DirectXCloseDisplay display
vout_directx debug: DirectXCloseDDraw
vout_directx debug: DirectXEventThread terminating
vout_directx debug: DirectXCloseWindow
vout_directx debug: WinProc WM_DESTROY
main debug: removing module "drawable"
main debug: thread ended
main debug: thread times: real 0m10,218750s, kernel 0m0,015625s, user 0m0,000000s
main debug: removing module "vout_directx"
main debug: removing module "blend"
main debug: thread times: real 0m0,078125s, kernel 0m0,015625s, user 0m0,000000s
main debug: removing module "freetype"
main debug: removing module "yuvp"
main debug: removing module "swscale"
main debug: deactivating the playlist
main debug: Deactivate
main debug: saving Media Library to file C:\Documents and Settings\fred\Application Data\vlc\ml.xspf
main debug: looking for playlist export module: 1 candidate
main debug: using playlist export module "export"
main debug: TIMER module_need() : 51,000 ms - Total 51,000 ms / 1 intvls (Avg 51,000 ms)
main debug: removing module "export"
main debug: Deactivated
main debug: removing all services discovery tasks
main debug: removing all interfaces
main debug: thread times: real 0m18,734375s, kernel 0m0,000000s, user 0m0,000000s
main debug: removing module "hotkeys"
main debug: thread times: real 0m0,031250s, kernel 0m0,000000s, user 0m0,000000s
-- logger module stopped --

Posted: **28 Aug 2009 22:54**

I have read it entirely and could not find it.

There should something like "mux_ts debug". VLC might also support ES streaming with RTP in which case you wouldn't need muxer (I am not sure about this).

but now I have lots of "stream_out_transcode debug: drift is too high, resetting master sync" and I don't know why.

Too high latency messes certain things up. It is internal problem that should be avoidable with some fixes, but I don't know if it is going to get better.

The VideoLAN Forums

mux=ts causing tons of warnings

mux=ts causing tons of warnings

Re: mux=ts causing tons of warnings

Re: mux=ts causing tons of warnings

Re: mux=ts causing tons of warnings