main debug: adding playlist item `/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi' ( /mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi )
main debug: creating new input thread
main debug: waiting for thread completion
main debug: thread 81926 (input) created at priority 0 (src/input/input.c:230)
main debug: `/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi' gives access `' demux `' path `/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi'
main debug: creating demux: access='' demux='' path='/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi'
main debug: looking for access_demux module: 1 candidate
main debug: creating access '' path='/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi'
main debug: looking for access2 module: 2 candidates
access_file debug: opening file `/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi'
main debug: using access2 module "access_file"
main debug: pre buffering
main debug: received first data for our buffer
main debug: prebuffering done 1408981 bytes in 0s - 512461 kbytes/s
main debug: creating demux: access='' demux='' path='/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi'
main debug: looking for demux2 module: 35 candidates
avi debug: found Chunk fourcc:46464952 (RIFF) size:180539534 pos:0
avi debug: found LIST chunk: 'AVI '
avi debug: <list 'AVI '>
avi debug: found Chunk fourcc:5453494c (LIST) size:8830 pos:12
avi debug: found LIST chunk: 'hdrl'
avi debug: <list 'hdrl'>
avi debug: found Chunk fourcc:68697661 (avih) size:56 pos:24
avi debug: avih: streams:2 flags: HAS_INDEX IS_INTERLEAVED 448x352
avi debug: found Chunk fourcc:5453494c (LIST) size:4244 pos:88
avi debug: found LIST chunk: 'strl'
avi debug: <list 'strl'>
avi debug: found Chunk fourcc:68727473 (strh) size:56 pos:100
avi debug: strh: type:vids handler:0x78766964 samplesize:0 23.98fps
avi debug: found Chunk fourcc:66727473 (strf) size:40 pos:164
avi debug: strf: video:DX50 448x352 planes:1 24bpp
avi debug: found Chunk fourcc:4b4e554a (JUNK) size:4120 pos:212
avi debug: </list 'strl'>
avi debug: found Chunk fourcc:5453494c (LIST) size:4234 pos:4340
avi debug: found LIST chunk: 'strl'
avi debug: <list 'strl'>
avi debug: found Chunk fourcc:68727473 (strh) size:56 pos:4352
avi debug: strh: type:auds handler:0x00000000 samplesize:0 38.28fps
avi debug: found Chunk fourcc:66727473 (strf) size:30 pos:4416
avi debug: strf: audio:0x0055 channels:2 44100Hz 0bits/sample 80kb/s
avi debug: found Chunk fourcc:4b4e554a (JUNK) size:4120 pos:4454
avi debug: </list 'strl'>
avi debug: found Chunk fourcc:5453494c (LIST) size:260 pos:8582
avi debug: found LIST chunk: 'odml'
avi debug: <list 'odml'>
avi debug: found Chunk fourcc:686c6d64 (dmlh) size:248 pos:8594
avi warning: unknown chunk (not loaded)
avi debug: </list 'odml'>
avi debug: </list 'hdrl'>
avi debug: found Chunk fourcc:5453494c (LIST) size:56 pos:8850
avi debug: found LIST chunk: 'INFO'
avi debug: <list 'INFO'>
avi debug: found Chunk fourcc:54465349 (ISFT) size:44 pos:8862
avi debug: ISFT: software : VirtualDubMod 1.5.10.1 (build 2366/release)
avi debug: </list 'INFO'>
avi debug: found Chunk fourcc:4b4e554a (JUNK) size:1318 pos:8914
avi debug: found Chunk fourcc:5453494c (LIST) size:177965894 pos:10240
avi debug: skipping movi chunk
avi debug: found Chunk fourcc:31786469 (idx1) size:2563392 pos:177976142
avi debug: idx1: index entry:160212
avi debug: </list 'AVI '>
avi debug: found Chunk fourcc:4b4e554a (JUNK) size:1890 pos:180539542
avi debug: * LIST-root size:180541440 pos:0
avi debug: + RIFF-AVI size:180539534 pos:0
avi debug: | + LIST-hdrl size:8830 pos:12
avi debug: | | + avih size:56 pos:24
avi debug: | | + LIST-strl size:4244 pos:88
avi debug: | | | + strh size:56 pos:100
avi debug: | | | + strf size:40 pos:164
avi debug: | | | + JUNK size:4120 pos:212
avi debug: | | + LIST-strl size:4234 pos:4340
avi debug: | | | + strh size:56 pos:4352
avi debug: | | | + strf size:30 pos:4416
avi debug: | | | + JUNK size:4120 pos:4454
avi debug: | | + LIST-odml size:260 pos:8582
avi debug: | | | + dmlh size:248 pos:8594
avi debug: | + LIST-INFO size:56 pos:8850
avi debug: | | + ISFT size:44 pos:8862
avi debug: | + JUNK size:1318 pos:8914
avi debug: | + LIST-movi size:177965894 pos:10240
avi debug: | + idx1 size:2563392 pos:177976142
avi debug: + JUNK size:1890 pos:180539542
avi debug: AVIH: 2 stream, flags HAS_INDEX IS_INTERLEAVED
avi debug: stream[0] rate:2997 scale:125 samplesize:0
avi debug: stream[0] video(DX50) 448x352 24bpp 23.976000fps
main debug: selecting program id=0
avi debug: stream[1] rate:44100 scale:1152 samplesize:0
avi debug: stream[1] audio(0x55) 2 channels 44100Hz 0bits
avi debug: stream[0] created 61699 index entries
avi debug: stream[1] created 98513 index entries
avi debug: stream[0] length:2573 (based on index)
avi debug: stream[1] length:2573 (based on index)
main debug: using demux2 module "avi"
main debug: looking for a subtitle file in /mnt/cdrom/
main debug: looking for decoder module: 21 candidates
ffmpeg debug: libavcodec initialized (interface 3342336 )
ffmpeg debug: postprocessing disabled
ffmpeg debug: ffmpeg codec (MPEG-4 Video) started
main debug: using decoder module "ffmpeg"
main debug: thread 98311 (decoder) created at priority 0 (src/input/decoder.c:159)
main debug: looking for decoder module: 21 candidates
main debug: using decoder module "mpeg_audio"
main debug: thread 114696 (decoder) created at priority 0 (src/input/decoder.c:159)
main debug: meta information:
main debug: - 'Setting' = ' HAS_INDEX IS_INTERLEAVED'
main debug: `/mnt/cdrom/Star Trek TNG - 3x06 - Booby Trap [NiteShdw 170mb].avi' successfully opened
avi debug: old:0 < new 0
avi debug: old:0 < new 0
main debug: no usable vout present, spawning one
main debug: looking for video output module: 5 candidates
xvideo debug: adaptor 0, port 73, format 0x32315659 (YV12) planar
mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:128
main debug: no aout present, spawning one
main debug: looking for audio output module: 6 candidates
main debug: Registering subpicture channel, ID: 2
main debug: Registering subpicture channel, ID: 3
main debug: Registering subpicture channel, ID: 4
main debug: Registering subpicture channel, ID: 5
xvideo debug: Window manager supports NetWM
arts debug: aRts initialized, latency 79000, 7 packets of size 2048
main debug: using audio output module "arts"
main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
main debug: filter(s) 'fl32'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 22 candidates
xvideo debug: Window manager supports _NET_WM_STATE_FULLSCREEN
xvideo debug: Window manager supports _NET_WM_STATE_ABOVE
xvideo debug: Window manager supports _NET_WM_STATE_BELOW
xvideo debug: Window manager supports _NET_WM_STATE_STAYS_ON_TOP
main debug: using video output module "xvideo"
main debug: waiting for thread completion
main debug: got 8 direct buffer(s)
main debug: picture in 448x352 (0,0,448x352), chroma I420, ar 274909:216000, sar 1:1
main debug: picture user 448x352 (0,0,448x352), chroma I420, ar 274909:216000, sar 1:1
main debug: picture out 448x352, chroma I420, ar 274909:216000, sar 3023999:3024000
main debug: direct render, mapping render pictures 0-6 to system pictures 1-7
main debug: thread 131081 (video output) created at priority 0 (src/video_output/video_output.c:466)
main debug: using audio filter module "float32tos16"
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: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes
main debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 22 candidates
main debug: using audio filter module "mpgatofixed32"
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: 22 candidates
main debug: using audio filter module "bandlimited_resampler"
main debug: found a filter for the whole conversion
main warning: PTS is out of range (-28737), dropping buffer
main warning: PTS is out of range (-22607), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: PTS is out of range (-38365), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: buffer is 52246 in advance, triggering downsampling
main warning: PTS is out of range (-39573), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: buffer is 52628 in advance, triggering downsampling
main warning: output PTS is out of range (4562), clearing out
main warning: input PTS is out of range (5818), trashing
main warning: PTS is out of range (-39011), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: output PTS is out of range (1204), clearing out
main warning: input PTS is out of range (20900), trashing
main warning: PTS is out of range (-37998), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: buffer is 61404 in advance, triggering downsampling
main warning: output PTS is out of range (1869), clearing out
main warning: input PTS is out of range (27958), trashing
main warning: input PTS is out of range (1817), trashing
main warning: PTS is out of range (-38858), dropping buffer
mpgatofixed32 debug: libmad error: Huffman data overrun
main warning: PTS is out of range (-39864), dropping buffer
main warning: buffer is 62535 in advance, triggering downsampling
main warning: output PTS is out of range (240), clearing out
main warning: input PTS is out of range (19030), trashing
main warning: PTS is out of range (-39358), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: buffer is 55757 in advance, triggering downsampling
main warning: output PTS is out of range (5988), clearing out
main warning: input PTS is out of range (22442), trashing
main warning: output PTS is out of range (3825), clearing out
main warning: input PTS is out of range (27443), trashing
main warning: input PTS is out of range (1143), trashing
main warning: PTS is out of range (-39533), dropping buffer
main warning: PTS is out of range (-35154), dropping buffer
main warning: output PTS is out of range (5636), clearing out
main warning: input PTS is out of range (31455), trashing
main warning: computed PTS is out of range (5355), clearing out
main warning: PTS is out of range (-32549), dropping buffer
main warning: PTS is out of range (-35326), dropping buffer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
main warning: buffer is 52242 in advance, triggering downsampling
main warning: PTS is out of range (-39715), dropping buffer
main warning: output PTS is out of range (7858), clearing out
main warning: input PTS is out of range (16397), trashing
main warning: output PTS is out of range (326), clearing out
main warning: input PTS is out of range (17760), trashing
main warning: PTS is out of range (-39532), dropping buffer
main warning: buffer is 60769 in advance, triggering downsampling
main warning: output PTS is out of range (412), clearing out
main warning: input PTS is out of range (23646), trashing
main warning: output PTS is out of range (4123), clearing out
main warning: input PTS is out of range (12436), trashing