Postby stoneysilence » 05 Oct 2009 06:12
I just played the video for 15-20 seconds and this is my log:
main debug: incoming request - stopping current input
main debug: dying input
main debug: control type=0
main debug: control: stopping input
main debug: dying input
main debug: thread times: real 6m42.744000s, kernel 0m0.109200s, user 0m0.374402s
avcodec debug: ffmpeg codec (MPEG-4 Video) stopped
main debug: removing module "avcodec"
main debug: killing decoder fourcc `XVID', 0 PES in FIFO
main debug: [0] 2 0
main debug: [1] 1 0
main debug: [2] 0 0
main debug: [3] 0 0
main debug: [4] 0 0
main debug: [5] 0 0
main debug: [6] 0 0
main debug: [7] 4 0
main debug: [8] 4 0
main debug: [9] 4 0
main debug: [10] 4 0
main debug: [11] 2 0
main debug: [12] 2 0
main debug: [13] 2 0
main debug: [14] 2 0
main debug: [15] 2 0
main debug: saving a free vout
main debug: thread times: real 6m42.744000s, kernel 0m0.015600s, user 0m0.046800s
main debug: removing module "mpeg_audio"
main debug: killing decoder fourcc `mpga', 0 PES in FIFO
main debug: removing module "mpgatofixed32"
main debug: removing module "scaletempo"
main debug: removing module "bandlimited_resampler"
aout_directx debug: closing audio device
aout_directx debug: DirectSoundThread exiting
main debug: thread ended
main debug: thread times: real 6m41.084000s, kernel 0m0.062400s, user 0m0.031200s
main debug: removing module "aout_directx"
main debug: removing module "converter_float"
main debug: removing module "float32_mixer"
main debug: releasing aout
avi debug: free chunk avih
avi debug: free chunk strh
avi debug: free chunk strf
avi debug: free chunk JUNK
avi debug: free chunk LIST
avi debug: free chunk strh
avi debug: free chunk strf
avi debug: free chunk JUNK
avi debug: free chunk LIST
avi warning: unknown chunk (not unloaded)
avi debug: free chunk LIST
avi debug: free chunk LIST
avi debug: free chunk ISFT
avi debug: free chunk LIST
avi debug: free chunk JUNK
avi debug: free chunk LIST
avi debug: free chunk idx1
avi debug: free chunk RIFF
avi debug: free chunk JUNK
avi debug: free chunk LIST
main debug: removing module "avi"
main debug: removing module "stream_filter_record"
main debug: removing module "access_file"
main debug: Program doesn't contain anymore ES
main debug: thread ended
main debug: dead input
main debug: thread times: real 6m44.844000s, kernel 0m0.062400s, user 0m0.031200s
main debug: processing request item Jimmy Neutron 58 - Best In Show.wmv node Playlist skip 0
main debug: resyncing on Jimmy Neutron 58 - Best In Show.wmv
main debug: Jimmy Neutron 58 - Best In Show.wmv is at 279
main debug: starting new item
main debug: creating new input thread
main debug: Creating an input for 'Jimmy Neutron 58 - Best In Show.wmv'
main debug: thread (input) created at priority 1 (../.././src/input/input.c:230)
main debug: thread started
main debug: using timeshift granularity of 50 MBytes
main debug: using timeshift path 'C:\Users\Eric\AppData\Local\Temp'
main debug: `file:///C:/Users/Eric/Videos/tv/Jimmy%20Neutron/Season%203/Jimmy%20Neutron%2058%20-%20Best%20In%20Show.wmv' gives access `file' demux `' path `C:\Users\Eric\Videos\tv\Jimmy Neutron\Season 3\Jimmy Neutron 58 - Best In Show.wmv'
main debug: creating demux: access='file' demux='' path='C:\Users\Eric\Videos\tv\Jimmy Neutron\Season 3\Jimmy Neutron 58 - Best In Show.wmv'
main debug: looking for access_demux module: 1 candidate
main warning: no access_demux module matching "file" could be loaded
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: creating access 'file' path='C:\Users\Eric\Videos\tv\Jimmy Neutron\Season 3\Jimmy Neutron 58 - Best In Show.wmv'
main debug: looking for access module: 2 candidates
access_file debug: opening file `C:\Users\Eric\Videos\tv\Jimmy Neutron\Season 3\Jimmy Neutron 58 - Best In Show.wmv'
main debug: using access module "access_file"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: Using AStream*Stream
main debug: pre buffering
main debug: received first data after 0 ms
main debug: pre-buffering done 1024 bytes in 0s - 1000000 kbytes/s
main debug: looking for stream_filter module: 2 candidates
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: looking for stream_filter module: 1 candidate
main debug: using stream_filter module "stream_filter_record"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: creating demux: access='file' demux='' path='C:\Users\Eric\Videos\tv\Jimmy Neutron\Season 3\Jimmy Neutron 58 - Best In Show.wmv'
main debug: looking for demux module: 50 candidates
asf debug: found object guid: 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:5620
asf debug: read "header object" subobj:8, reserved1:1, reserved2:2
asf debug: found object guid: 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 size:300
asf debug: read "extended content description object"
asf debug: - 'Application' = 'Windows Movie Maker 2.0.3312.0'
asf debug: - 'WM/ParentalRating' = ''
asf debug: - 'WMFSDKVersion' = '10.00.00.3802'
asf debug: - 'WMFSDKNeeded' = '0.0.0.0000'
asf debug: - 'IsVBR' = 'false'
asf debug: found object guid: 0x75b22633-0x668e-0x11cf-0xa6d900aa0062ce6c size:52
asf debug: read "content description object" title:"" artist:"Flank" copyright:"" description:"" rating:""
asf debug: found object guid: 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104
asf debug: read "file properties object" file_id:0x90370b5-0xb67c-0x40ed-0xbf15651ee27e39cb file_size:116657566 creation_date:128349477573900000 data_packets_count:14581 play_duration:6383600000 send_duration:6344090000 preroll:5000 flags:2 min_data_packet_size:8000 max_data_packet_size:8000 max_bitrate:1478856
asf debug: found object guid: 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:4499
asf debug: read "header extension object" reserved1:0xabd3d211-0xa9ba-0x11cf-0x8ee600c00c205365 reserved2:6 header_extension_size:4453
asf debug: found object guid: 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 size:39
asf debug: read "language list object" 1 entries
asf debug: - 'en-us'
asf debug: found object guid: 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 size:26
asf warning: unknown asf object (not loaded)
asf debug: found object guid: 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca size:472
asf debug: read "metadata object" 8 entries
asf debug: - IsVBR=0
asf debug: - DeviceConformanceTemplate=M2
asf debug: - IsVBR=0
asf debug: - DeviceConformanceTemplate=AP@L1
asf debug: - WM/WMADRCPeakReference=28288
asf debug: - WM/WMADRCPeakTarget=28288
asf debug: - WM/WMADRCAverageReference=3305
asf debug: - WM/WMADRCAverageTarget=3305
asf debug: found object guid: 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 size:3602
asf warning: unknown asf object (not loaded)
asf debug: found object guid: 0xd4fed15b-0x88d3-0x454f-0x81f0ed5c45999e24 size:34
asf debug: read "stream prioritization object"
asf debug: - Stream:1 flags=0x0
asf debug: - Stream:2 flags=0x0
asf debug: found object guid: 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88
asf debug: read "extended stream properties object":
asf debug: - start=0 end=0
asf debug: - data bitrate=256032 buffer=5000 initial fullness=0
asf debug: - alternate data bitrate=256032 buffer=5000 initial fullness=0
asf debug: - maximum object size=5462
asf debug: - flags=0x2
asf debug: - stream number=1 language=0
asf debug: - average time per frame=1690048
asf debug: - stream name count=0
asf debug: - payload extension system count=0
asf debug: found object guid: 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:154
asf debug: read "extended stream properties object":
asf debug: - start=0 end=0
asf debug: - data bitrate=1208000 buffer=5000 initial fullness=0
asf debug: - alternate data bitrate=1208000 buffer=5000 initial fullness=0
asf debug: - maximum object size=56755
asf debug: - flags=0x2
asf debug: - stream number=2 language=0
asf debug: - average time per frame=400000
asf debug: - stream name count=0
asf debug: - payload extension system count=3
asf debug: found object guid: 0xd9aade20-0x7c17-0x4f9c-0xbc288555dd98e2a2 size:38
asf warning: unknown asf object (not loaded)
asf debug: found object guid: 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:324
asf debug: read "codec list object" reserved_guid:0x86d15241-0x311d-0x11d0-0xa3a400a0c90348f6 codec_entries_count:2
asf debug: - codec[0] audio name:"Windows Media Audio 9.1 Professional" description:"256 kbps, 96 kHz, 2 channel 24 bit (A/V) 1-pass CBR" information_length:2
asf debug: - codec[1] video name:"Windows Media Video 9 Advanced Profile" description:"" information_length:4
asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:122
asf debug: read "stream Properties object" stream_type:0xf8699e40-0x5b4d-0x11cf-0xa8fd00805f5c442b error_correction_type:0xbfc3cd50-0x618f-0x11cf-0x8bb200aa00b4e220 time_offset:0 type_specific_data_length:36 error_correction_data_length:8 flags:0x1 stream_number:1
asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:151
asf debug: read "stream Properties object" stream_type:0xbc19efc0-0x5b4d-0x11cf-0xa8fd00805f5c442b error_correction_type:0x20fb5700-0x5b55-0x11cf-0xa8fd00805f5c442b time_offset:0 type_specific_data_length:73 error_correction_data_length:0 flags:0x2 stream_number:2
asf debug: found object guid: 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 size:38
asf debug: read "stream bitrate properties object"
asf debug: - stream=1 bitrate=259027
asf debug: - stream=2 bitrate=1219829
asf debug: found object guid: 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:116648050
asf debug: read "data object" file_id:0x90370b5-0xb67c-0x40ed-0xbf15651ee27e39cb total data packet:14581 reserved:257
asf debug: found object guid: 0x33000890-0xe5b1-0x11cf-0x89f400a0c90349cb size:3896
asf debug: read "index object" file_id:0x90370b5-0xb67c-0x40ed-0xbf15651ee27e39cb index_entry_time_interval:10000000 max_packet_count:10 index_entry_count:640
asf debug: + 'Unknown' GUID 0x0-0x0-0x0-0x0000000000000000 size:0pos:0
asf debug: + 'Header' GUID 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:5620pos:0
asf debug: | + 'Extended content description' GUID 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 size:300pos:30
asf debug: | + 'Content Description' GUID 0x75b22633-0x668e-0x11cf-0xa6d900aa0062ce6c size:52pos:330
asf debug: | + 'File Properties' GUID 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104pos:382
asf debug: | + 'Header Extension' GUID 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:4499pos:486
asf debug: | | + 'Language List' GUID 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 size:39pos:532
asf debug: | | + 'Unknown' GUID 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 size:26pos:571
asf debug: | | + 'Metadata' GUID 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca size:472pos:597
asf debug: | | + 'Padding' GUID 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 size:3602pos:1069
asf debug: | | + 'Stream Prioritization' GUID 0xd4fed15b-0x88d3-0x454f-0x81f0ed5c45999e24 size:34pos:4671
asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88pos:4705
asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:154pos:4793
asf debug: | | + 'Unknown' GUID 0xd9aade20-0x7c17-0x4f9c-0xbc288555dd98e2a2 size:38pos:4947
asf debug: | + 'Codec List' GUID 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:324pos:4985
asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:122pos:5309
asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:151pos:5431
asf debug: | + 'Stream Bitrate Properties' GUID 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 size:38pos:5582
asf debug: + 'Data' GUID 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:116648050pos:5620
asf debug: + 'Index' GUID 0x33000890-0xe5b1-0x11cf-0x89f400a0c90349cb size:3896pos:116653670
asf debug: found 2 streams
main debug: selecting program id=0
asf debug: added new audio stream(codec:0x162,ID:1)
asf debug: added new video stream(ID:2)
main debug: using demux module "asf"
main debug: TIMER module_need() : 5.000 ms - Total 5.000 ms / 1 intvls (Avg 5.000 ms)
main debug: looking for a subtitle file in C:\Program Files (x86)\VideoLAN\VLC\
main debug: looking for decoder module: 36 candidates
avcodec debug: libavcodec already initialized
avcodec debug: ffmpeg codec (Windows Media Audio Professional) started
avcodec debug: Using 196608 bytes output buffer
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 5.000 ms - Total 5.000 ms / 1 intvls (Avg 5.000 ms)
main debug: thread (decoder) created at priority 2 (../.././src/input/decoder.c:315)
main debug: thread started
main debug: looking for decoder module: 36 candidates
avcodec debug: libavcodec already initialized
avcodec debug: using direct rendering
qt4 debug: IM: Deleting the input
qt4 debug: Updating the geometry
qt4 debug: Updating the geometry
qt4 debug: Qt: Entering Fullscreen
avcodec debug: ffmpeg codec (Windows Media Video Advanced ProfileMSVC/) started
main debug: using decoder module "avcodec"
main debug: TIMER module_need() : 15.000 ms - Total 15.000 ms / 1 intvls (Avg 15.000 ms)
main debug: thread (decoder) created at priority 0 (../.././src/input/decoder.c:315)
main debug: thread started
main debug: `file:///C:/Users/Eric/Videos/tv/Jimmy%20Neutron/Season%203/Jimmy%20Neutron%2058%20-%20Best%20In%20Show.wmv' successfully opened
main debug: Buffering 0%
main debug: Buffering 0%
main debug: Buffering 56%
main debug: Buffering 13%
main debug: Buffering 26%
main debug: Buffering 26%
main debug: Buffering 53%
main debug: Buffering 80%
main debug: Buffering 93%
main debug: Stream buffering done (360 ms in 0 ms)
main debug: TIMER input launching for 'Ed, Edd n' Eddy - S3E01 - Wish You Were Ed ~ Momma's Little Ed.avi' : 2010.000 ms - Total 2010.000 ms / 1 intvls (Avg 2010.000 ms)
qt4 debug: IM: Setting an input
main debug: trying to reuse free vout
main debug: reusing provided vout
qt4 debug: Updating the geometry
qt4 debug: Updating the geometry
qt4 debug: Qt: Entering Fullscreen
main debug: End of video preroll
main debug: Received first picture
main debug: Decoder buffering done in 25 ms
freetype debug: using fontsize: 30
main debug: reusing aout
main debug: looking for audio output module: 3 candidates
aout_directx debug: OpenAudio
aout_directx debug: found device: Primary Sound Driver
aout_directx debug: found device: Speakers (High Definition Audio Device)
aout_directx debug: found device: Digital Audio (S/PDIF) (High Definition Audio Device)
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 started
aout_directx debug: DirectSoundThread ready
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() : 20.000 ms - Total 20.000 ms / 1 intvls (Avg 20.000 ms)
main debug: output 's16l' 96000 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'fl32' 96000 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'fl32'->'s16l' 96000 Hz->96000 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: using audio filter module "converter_float"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: found a filter for the whole conversion
main debug: looking for audio mixer module: 3 candidates
main debug: using audio mixer module "float32_mixer"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: input 'fl32' 96000 Hz Stereo frame=1 samples/8 bytes
main debug: looking for audio filter module: 1 candidate
scaletempo debug: format: 96000 rate, 2 nch, 4 bps, fl32
scaletempo debug: params: 30 stride, 0.200 overlap, 14 search
scaletempo debug: 1.000 scale, 2880.000 stride_in, 2880 stride_out, 2304 standing, 576 overlap, 1344 search, 4800 queue, fl32 mode
main debug: using audio filter module "scaletempo"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: filter(s) 'fl32'->'fl32' 105600 Hz->96000 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: using audio filter module "bandlimited_resampler"
main debug: TIMER module_need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms)
main debug: found a filter for the whole conversion
main debug: End of audio preroll
main warning: computed PTS is out of range (812335), clearing out
main warning: audio drift is too big (-256334), clearing out
main warning: computed PTS is out of range (11468335), clearing out
main warning: computed PTS is out of range (8920335), clearing out
main warning: audio drift is too big (-309334), clearing out
main warning: computed PTS is out of range (1268335), clearing out
main warning: computed PTS is out of range (222335), clearing out
main warning: computed PTS is out of range (2862335), clearing out
main warning: computed PTS is out of range (3651335), clearing out