-- logger module started -- main debug: CPU has capabilities 486 586 MMX MMXEXT SSE SSE2 FPU main debug: looking for memcpy module: 3 candidates main debug: using memcpy module "memcpymmxext" main debug: thread 4660 (Interaction control) created at priority 0 (interface/interaction.c:382) main debug: thread started main debug: Creating an input for 'Media Library' main debug: Input is a meta file: disabling unneeded options main debug: `file/xspf-open://C:\Documents and Settings\Default User\Application Data\vlc\ml.xspf' gives access `file' demux `xspf-open' path `C:\Documents and Settings\Default User\Application Data\vlc\ml.xspf' main debug: creating access 'file' path='C:\Documents and Settings\Default User\Application Data\vlc\ml.xspf' main debug: looking for access module: 2 candidates access_file debug: opening file `C:\Documents and Settings\Default User\Application Data\vlc\ml.xspf' main debug: using access module "access_file" main debug: TIMER module_Need() : 29.000 ms - Total 29.000 ms / 1 intvls (Avg 29.000 ms) main debug: Using AStream*Stream main debug: pre-buffering... main debug: received first data for our buffer main debug: creating demux: access='file' demux='xspf-open' path='C:\Documents and Settings\Default User\Application Data\vlc\ml.xspf' main debug: looking for demux module: 1 candidate playlist debug: using XSPF playlist reader main debug: using demux module "playlist" main debug: TIMER module_Need() : 35.000 ms - Total 35.000 ms / 1 intvls (Avg 35.000 ms) main debug: `file/xspf-open://C:\Documents and Settings\Default User\Application Data\vlc\ml.xspf' successfully opened main debug: looking for xml module: 2 candidates main debug: using xml module "xml" main debug: TIMER module_Need() : 34.000 ms - Total 34.000 ms / 1 intvls (Avg 34.000 ms) playlist debug: parsed 0 tracks successfully main debug: removing module "xml" main debug: EOF reached main debug: control type=1 main debug: removing module "playlist" main debug: removing module "access_file" main debug: TIMER input launching for 'Media Library' : 89.000 ms - Total 89.000 ms / 1 intvls (Avg 89.000 ms) main debug: waiting for thread initialization main debug: thread started main debug: thread 4736 (preparser) created at priority 0 (playlist/thread.c:79) main debug: waiting for thread initialization main debug: thread started main debug: thread 4748 (fetcher) created at priority 0 (playlist/thread.c:108) main debug: waiting for thread initialization main debug: thread started main debug: rebuilding array of current - root Playlist main debug: rebuild done - 0 items, index -1 main debug: thread 4744 (playlist) created at priority 0 (playlist/thread.c:117) main debug: looking for interface module: 1 candidate logger: VLC media player - version 0.9.8a Grishenko - (c) 1996-2008 the VideoLAN team logger: Warning: if you can't access the GUI anymore, open a command-line window, go to the directory where you installed VLC and run "vlc -I qt" logger: using logger... logger debug: opening logfile `C:\Program Files\SageTV\webfeedencoder\logs\KJZZ_saveasmp3_test.log' main debug: using interface module "logger" main debug: TIMER module_Need() : 12.000 ms - Total 12.000 ms / 1 intvls (Avg 12.000 ms) main debug: thread 4768 (interface) created at priority 0 (interface/interface.c:168) main debug: thread started main debug: looking for interface module: 1 candidate main debug: using interface module "hotkeys" main debug: TIMER module_Need() : 17.000 ms - Total 17.000 ms / 1 intvls (Avg 17.000 ms) main debug: thread 4792 (interface) created at priority 0 (interface/interface.c:168) main debug: thread started main debug: adding item `http://opml.radiotime.com/Tune.ashx?id=s33408' ( http://opml.radiotime.com/Tune.ashx?id=s33408 ) main debug: looking for interface module: 15 candidates rc debug: base: localhost, port: 5002 main debug: net: listening to localhost port 5002 main debug: using interface module "rc" main debug: TIMER module_Need() : 17.000 ms - Total 17.000 ms / 1 intvls (Avg 17.000 ms) main debug: thread 4860 (interface) created at priority 0 (interface/interface.c:168) main debug: thread started main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: starting new item main debug: processing request item null node Playlist skip 0 main debug: creating new input thread main debug: Creating an input for 'http://opml.radiotime.com/Tune.ashx?id=s33408' main debug: waiting for thread initialization main debug: thread started main debug: thread 4812 (input) created at priority 1 (input/input.c:370) main debug: stream=`transcode' main debug: looking for sout stream module: 1 candidate main debug: stream=`duplicate' main debug: looking for sout stream module: 1 candidate stream_out_duplicate debug: creating 'duplicate' stream_out_duplicate debug: * adding `std{access=file,mux=raw,dst='C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3'}' main debug: stream=`std' main debug: looking for sout stream module: 1 candidate main debug: set config option: sout-standard-access to file main debug: set config option: sout-standard-mux to raw main debug: set config option: sout-standard-dst to C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3 stream_out_standard debug: creating `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' stream_out_standard debug: extension is mp3 stream_out_standard debug: extension -> mux=(null) stream_out_standard debug: using `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' main debug: looking for sout access module: 1 candidate access_output_file debug: file access output opened (C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3) main debug: using sout access module "access_output_file" main debug: TIMER module_Need() : 10.000 ms - Total 10.000 ms / 1 intvls (Avg 10.000 ms) stream_out_standard debug: access opened main debug: looking for sout mux module: 1 candidate mux_dummy debug: Dummy/Raw muxer opened mux_dummy: Open main debug: using sout mux module "mux_dummy" main debug: TIMER module_Need() : 30.000 ms - Total 30.000 ms / 1 intvls (Avg 30.000 ms) main debug: muxer support adding stream at any time main debug: muxer prefers to wait for all ES before starting to mux stream_out_standard debug: mux opened main debug: using sout stream module "stream_out_standard" main debug: TIMER module_Need() : 80.000 ms - Total 80.000 ms / 1 intvls (Avg 80.000 ms) main debug: using sout stream module "stream_out_duplicate" main debug: TIMER module_Need() : 84.000 ms - Total 84.000 ms / 1 intvls (Avg 84.000 ms) main debug: set config option: sout-transcode-acodec to mp3 main debug: set config option: sout-transcode-ab to 128 main debug: set config option: sout-transcode-channels to 2 stream_out_transcode debug: codec audio=mp3 0Hz 2 channels 128Kb/s main debug: using sout stream module "stream_out_transcode" main debug: TIMER module_Need() : 100.000 ms - Total 100.000 ms / 1 intvls (Avg 100.000 ms) main debug: `http://opml.radiotime.com/Tune.ashx?id=s33408' gives access `http' demux `' path `opml.radiotime.com/Tune.ashx?id=s33408' main debug: creating demux: access='http' demux='' path='opml.radiotime.com/Tune.ashx?id=s33408' main debug: looking for access_demux module: 0 candidates main warning: no access_demux module matched "http" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'http' path='opml.radiotime.com/Tune.ashx?id=s33408' main debug: looking for access module: 2 candidates access_http debug: http: server='opml.radiotime.com' port=80 file='/Tune.ashx?id=s33408 main debug: net: connecting to opml.radiotime.com port 80 main debug: connection: Resource temporarily unavailable main debug: connection succeeded (socket = 5100) access_http debug: protocol 'HTTP' answer code 200 access_http debug: Server: Microsoft-IIS/6.0 access_http debug: Content-Type: audio/x-mpegurl; charset=utf-8 access_http debug: this frame size=118 main debug: using access module "access_http" main debug: TIMER module_Need() : 294.000 ms - Total 294.000 ms / 1 intvls (Avg 294.000 ms) main debug: Using AStream*Stream main debug: pre-buffering... main debug: received first data for our buffer main debug: creating demux: access='http' demux='' path='opml.radiotime.com/Tune.ashx?id=s33408' main debug: looking for demux module: 51 candidates playlist debug: found valid M3U playlist main debug: using demux module "playlist" main debug: TIMER module_Need() : 976.000 ms - Total 976.000 ms / 1 intvls (Avg 976.000 ms) main debug: looking for a subtitle file in C:\Program Files\SageTV\webfeedencoder\wrapper\ main debug: starting in sync mode main debug: `http://opml.radiotime.com/Tune.ashx?id=s33408' successfully opened main debug: deleting item `http://opml.radiotime.com/Tune.ashx?id=s33408' main debug: rebuilding array of current - root Playlist main debug: rebuild done - 1 items, index -1 main debug: no fetch required for http://riosoleado.streamguys.org/kjzz (art currently (null)) main debug: EOF reached main debug: control type=1 main debug: finished input main debug: waitpipe: object killed main debug: dying input main debug: no fetch required for http://kjzz.streamguys.org/listen.pls (art currently (null)) main debug: no fetch required for http://kjzz-low.streamguys.org/listen.pls (art currently (null)) main debug: dying input main debug: removing module "playlist" main debug: removing module "access_http" main debug: thread ended main debug: dead input main debug: thread times: real 0m1.468750s, kernel 0m0.046875s, user 0m0.046875s main debug: thread 4812 joined (playlist/engine.c:244) main debug: TIMER input launching for 'http://opml.radiotime.com/Tune.ashx?id=s33408' : 1374.000 ms - Total 1374.000 ms / 1 intvls (Avg 1374.000 ms) main debug: destroying chain... (name=transcode) main debug: destroying chain... (name=duplicate) stream_out_duplicate debug: closing a duplication main debug: destroying chain... (name=std) mux_dummy debug: Dummy/Raw muxer closed main debug: removing module "mux_dummy" access_output_file debug: file access output closed main debug: removing module "access_output_file" main debug: removing module "stream_out_standard" main debug: destroying chain done main debug: removing module "stream_out_duplicate" main debug: destroying chain done main debug: removing module "stream_out_transcode" main debug: destroying chain done main debug: starting new item main debug: changing item without a request (current -1/1) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 3 items, index -1 main debug: using item 0 main debug: creating new input thread main debug: Creating an input for 'http://riosoleado.streamguys.org/kjzz' main debug: waiting for thread initialization main debug: thread started main debug: thread 4960 (input) created at priority 1 (input/input.c:370) main debug: stream=`transcode' main debug: looking for sout stream module: 1 candidate main debug: stream=`duplicate' main debug: looking for sout stream module: 1 candidate stream_out_duplicate debug: creating 'duplicate' stream_out_duplicate debug: * adding `std{access=file,mux=raw,dst='C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3'}' main debug: stream=`std' main debug: looking for sout stream module: 1 candidate main debug: set config option: sout-standard-access to file main debug: set config option: sout-standard-mux to raw main debug: set config option: sout-standard-dst to C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3 stream_out_standard debug: creating `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' stream_out_standard debug: extension is mp3 stream_out_standard debug: extension -> mux=(null) stream_out_standard debug: using `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' main debug: looking for sout access module: 1 candidate access_output_file debug: file access output opened (C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3) main debug: using sout access module "access_output_file" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) stream_out_standard debug: access opened main debug: looking for sout mux module: 1 candidate mux_dummy debug: Dummy/Raw muxer opened mux_dummy: Open main debug: using sout mux module "mux_dummy" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: muxer support adding stream at any time main debug: muxer prefers to wait for all ES before starting to mux stream_out_standard debug: mux opened main debug: using sout stream module "stream_out_standard" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: using sout stream module "stream_out_duplicate" main debug: TIMER module_Need() : 5.000 ms - Total 5.000 ms / 1 intvls (Avg 5.000 ms) main debug: set config option: sout-transcode-acodec to mp3 main debug: set config option: sout-transcode-ab to 128 main debug: set config option: sout-transcode-channels to 2 stream_out_transcode debug: codec audio=mp3 0Hz 2 channels 128Kb/s main debug: using sout stream module "stream_out_transcode" main debug: TIMER module_Need() : 11.000 ms - Total 11.000 ms / 1 intvls (Avg 11.000 ms) main debug: `http://riosoleado.streamguys.org/kjzz' gives access `http' demux `' path `riosoleado.streamguys.org/kjzz' main debug: creating demux: access='http' demux='' path='riosoleado.streamguys.org/kjzz' main debug: looking for access_demux module: 0 candidates main warning: no access_demux module matched "http" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'http' path='riosoleado.streamguys.org/kjzz' main debug: looking for access module: 2 candidates access_http debug: http: server='riosoleado.streamguys.org' port=80 file='/kjzz main debug: net: connecting to riosoleado.streamguys.org port 80 main debug: connection: Resource temporarily unavailable main debug: connection succeeded (socket = 5136) access_http debug: protocol 'HTTP' answer code 200 access_http debug: Content-Type: video/x-ms-asf access_http debug: Server: Cougar/9.5.6001.18281 access_http debug: this frame size=119 access_http debug: Pragma: no-cache, xResetStrm=1 access_http debug: Connection: close main debug: using access module "access_http" main debug: TIMER module_Need() : 412.000 ms - Total 412.000 ms / 1 intvls (Avg 412.000 ms) main debug: Using AStream*Stream main debug: pre-buffering... main debug: received first data for our buffer main debug: creating demux: access='http' demux='' path='riosoleado.streamguys.org/kjzz' main debug: looking for demux module: 51 candidates playlist debug: found valid PLS playlist file main debug: using demux module "playlist" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for a subtitle file in C:\Program Files\SageTV\webfeedencoder\wrapper\ main debug: starting in sync mode main debug: `http://riosoleado.streamguys.org/kjzz' successfully opened main debug: deleting item `http://riosoleado.streamguys.org/kjzz' main debug: no fetch required for mms://riosoleado.streamguys.org/kjzz?MSWMExt=.asf (art currently (null)) main debug: EOF reached main debug: control type=1 main debug: no fetch required for mms://216.246.51.219:80/kjzz?MSWMExt=.asf (art currently (null)) main debug: rebuilding array of current - root Playlist main debug: rebuild done - 4 items, index -1 main debug: finished input main debug: waitpipe: object killed main debug: dying input main debug: removing module "playlist" main debug: removing module "access_http" main debug: thread ended main debug: dead input main debug: thread times: real 0m0.515625s, kernel 0m0.000000s, user 0m0.015625s main debug: thread 4960 joined (playlist/engine.c:244) main debug: TIMER input launching for 'http://riosoleado.streamguys.org/kjzz' : 427.000 ms - Total 427.000 ms / 1 intvls (Avg 427.000 ms) main debug: destroying chain... (name=transcode) main debug: destroying chain... (name=duplicate) stream_out_duplicate debug: closing a duplication main debug: destroying chain... (name=std) mux_dummy debug: Dummy/Raw muxer closed main debug: removing module "mux_dummy" access_output_file debug: file access output closed main debug: removing module "access_output_file" main debug: removing module "stream_out_standard" main debug: destroying chain done main debug: removing module "stream_out_duplicate" main debug: destroying chain done main debug: removing module "stream_out_transcode" main debug: destroying chain done main debug: starting new item main debug: changing item without a request (current -1/4) main debug: using item 0 main debug: creating new input thread main debug: Creating an input for 'http://kjzz.streamguys.org/listen.pls' main debug: waiting for thread initialization main debug: thread started main debug: thread 4968 (input) created at priority 1 (input/input.c:370) main debug: stream=`transcode' main debug: looking for sout stream module: 1 candidate main debug: stream=`duplicate' main debug: looking for sout stream module: 1 candidate stream_out_duplicate debug: creating 'duplicate' stream_out_duplicate debug: * adding `std{access=file,mux=raw,dst='C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3'}' main debug: stream=`std' main debug: looking for sout stream module: 1 candidate main debug: set config option: sout-standard-access to file main debug: set config option: sout-standard-mux to raw main debug: set config option: sout-standard-dst to C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3 stream_out_standard debug: creating `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' stream_out_standard debug: extension is mp3 stream_out_standard debug: extension -> mux=(null) stream_out_standard debug: using `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' main debug: looking for sout access module: 1 candidate access_output_file debug: file access output opened (C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3) main debug: using sout access module "access_output_file" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) stream_out_standard debug: access opened main debug: looking for sout mux module: 1 candidate mux_dummy debug: Dummy/Raw muxer opened mux_dummy: Open main debug: using sout mux module "mux_dummy" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: muxer support adding stream at any time main debug: muxer prefers to wait for all ES before starting to mux stream_out_standard debug: mux opened main debug: using sout stream module "stream_out_standard" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: using sout stream module "stream_out_duplicate" main debug: TIMER module_Need() : 5.000 ms - Total 5.000 ms / 1 intvls (Avg 5.000 ms) main debug: set config option: sout-transcode-acodec to mp3 main debug: set config option: sout-transcode-ab to 128 main debug: set config option: sout-transcode-channels to 2 stream_out_transcode debug: codec audio=mp3 0Hz 2 channels 128Kb/s main debug: using sout stream module "stream_out_transcode" main debug: TIMER module_Need() : 11.000 ms - Total 11.000 ms / 1 intvls (Avg 11.000 ms) main debug: `http://kjzz.streamguys.org/listen.pls' gives access `http' demux `' path `kjzz.streamguys.org/listen.pls' main debug: creating demux: access='http' demux='' path='kjzz.streamguys.org/listen.pls' main debug: looking for access_demux module: 0 candidates main warning: no access_demux module matched "http" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'http' path='kjzz.streamguys.org/listen.pls' main debug: looking for access module: 2 candidates access_http debug: http: server='kjzz.streamguys.org' port=80 file='/listen.pls main debug: net: connecting to kjzz.streamguys.org port 80 main debug: connection: Resource temporarily unavailable main debug: connection succeeded (socket = 5192) access_http debug: protocol 'HTTP' answer code 200 access_http debug: Content-Type: audio/x-scpls access_http debug: Connection: close main debug: using access module "access_http" main debug: TIMER module_Need() : 464.000 ms - Total 464.000 ms / 1 intvls (Avg 464.000 ms) main debug: Using AStream*Stream main debug: pre-buffering... main debug: received first data for our buffer main debug: creating demux: access='http' demux='' path='kjzz.streamguys.org/listen.pls' main debug: looking for demux module: 51 candidates playlist debug: found valid PLS playlist file main debug: using demux module "playlist" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for a subtitle file in C:\Program Files\SageTV\webfeedencoder\wrapper\ main debug: starting in sync mode main debug: `http://kjzz.streamguys.org/listen.pls' successfully opened playlist debug: pls should have 1 entries main debug: deleting item `http://kjzz.streamguys.org/listen.pls' main debug: EOF reached main debug: no fetch required for http://kjzz.streamguys.org:80/ (art currently (null)) main debug: control type=1 main debug: rebuilding array of current - root Playlist main debug: rebuild done - 4 items, index -1 main debug: finished input main debug: waitpipe: object killed main debug: dying input main debug: dying input main debug: removing module "playlist" main debug: removing module "access_http" main debug: thread ended main debug: dead input main debug: thread times: real 0m0.578125s, kernel 0m0.000000s, user 0m0.000000s main debug: thread 4968 joined (playlist/engine.c:244) main debug: TIMER input launching for 'http://kjzz.streamguys.org/listen.pls' : 480.000 ms - Total 480.000 ms / 1 intvls (Avg 480.000 ms) main debug: destroying chain... (name=transcode) main debug: destroying chain... (name=duplicate) stream_out_duplicate debug: closing a duplication main debug: destroying chain... (name=std) mux_dummy debug: Dummy/Raw muxer closed main debug: removing module "mux_dummy" access_output_file debug: file access output closed main debug: removing module "access_output_file" main debug: removing module "stream_out_standard" main debug: destroying chain done main debug: removing module "stream_out_duplicate" main debug: destroying chain done main debug: removing module "stream_out_transcode" main debug: destroying chain done main debug: starting new item main debug: changing item without a request (current -1/4) main debug: using item 0 main debug: creating new input thread main debug: Creating an input for 'http://kjzz-low.streamguys.org/listen.pls' main debug: waiting for thread initialization main debug: thread started main debug: thread 4980 (input) created at priority 1 (input/input.c:370) main debug: stream=`transcode' main debug: looking for sout stream module: 1 candidate main debug: stream=`duplicate' main debug: looking for sout stream module: 1 candidate stream_out_duplicate debug: creating 'duplicate' stream_out_duplicate debug: * adding `std{access=file,mux=raw,dst='C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3'}' main debug: stream=`std' main debug: looking for sout stream module: 1 candidate main debug: set config option: sout-standard-access to file main debug: set config option: sout-standard-mux to raw main debug: set config option: sout-standard-dst to C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3 stream_out_standard debug: creating `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' stream_out_standard debug: extension is mp3 stream_out_standard debug: extension -> mux=(null) stream_out_standard debug: using `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' main debug: looking for sout access module: 1 candidate access_output_file debug: file access output opened (C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3) main debug: using sout access module "access_output_file" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) stream_out_standard debug: access opened main debug: looking for sout mux module: 1 candidate mux_dummy debug: Dummy/Raw muxer opened mux_dummy: Open main debug: using sout mux module "mux_dummy" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: muxer support adding stream at any time main debug: muxer prefers to wait for all ES before starting to mux stream_out_standard debug: mux opened main debug: using sout stream module "stream_out_standard" main debug: TIMER module_Need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms) main debug: using sout stream module "stream_out_duplicate" main debug: TIMER module_Need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms) main debug: set config option: sout-transcode-acodec to mp3 main debug: set config option: sout-transcode-ab to 128 main debug: set config option: sout-transcode-channels to 2 stream_out_transcode debug: codec audio=mp3 0Hz 2 channels 128Kb/s main debug: using sout stream module "stream_out_transcode" main debug: TIMER module_Need() : 9.000 ms - Total 9.000 ms / 1 intvls (Avg 9.000 ms) main debug: `http://kjzz-low.streamguys.org/listen.pls' gives access `http' demux `' path `kjzz-low.streamguys.org/listen.pls' main debug: creating demux: access='http' demux='' path='kjzz-low.streamguys.org/listen.pls' main debug: looking for access_demux module: 0 candidates main warning: no access_demux module matched "http" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'http' path='kjzz-low.streamguys.org/listen.pls' main debug: looking for access module: 2 candidates access_http debug: http: server='kjzz-low.streamguys.org' port=80 file='/listen.pls main debug: net: connecting to kjzz-low.streamguys.org port 80 main debug: connection: Resource temporarily unavailable main debug: connection succeeded (socket = 5092) access_http debug: protocol 'HTTP' answer code 200 access_http debug: Content-Type: audio/x-scpls access_http debug: Connection: close main debug: using access module "access_http" main debug: TIMER module_Need() : 417.000 ms - Total 417.000 ms / 1 intvls (Avg 417.000 ms) main debug: Using AStream*Stream main debug: pre-buffering... main debug: received first data for our buffer main debug: creating demux: access='http' demux='' path='kjzz-low.streamguys.org/listen.pls' main debug: looking for demux module: 51 candidates playlist debug: found valid PLS playlist file main debug: using demux module "playlist" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: looking for a subtitle file in C:\Program Files\SageTV\webfeedencoder\wrapper\ main debug: starting in sync mode main debug: `http://kjzz-low.streamguys.org/listen.pls' successfully opened playlist debug: pls should have 1 entries main debug: deleting item `http://kjzz-low.streamguys.org/listen.pls' main debug: EOF reached main debug: no fetch required for http://kjzz-low.streamguys.org:80/ (art currently (null)) main debug: control type=1 main debug: rebuilding array of current - root Playlist main debug: rebuild done - 4 items, index -1 main debug: finished input main debug: waitpipe: object killed main debug: dying input main debug: removing module "playlist" main debug: removing module "access_http" main debug: thread ended main debug: dead input main debug: thread times: real 0m0.531250s, kernel 0m0.000000s, user 0m0.015625s main debug: thread 4980 joined (playlist/engine.c:244) main debug: TIMER input launching for 'http://kjzz-low.streamguys.org/listen.pls' : 432.000 ms - Total 432.000 ms / 1 intvls (Avg 432.000 ms) main debug: destroying chain... (name=transcode) main debug: destroying chain... (name=duplicate) stream_out_duplicate debug: closing a duplication main debug: destroying chain... (name=std) mux_dummy debug: Dummy/Raw muxer closed main debug: removing module "mux_dummy" access_output_file debug: file access output closed main debug: removing module "access_output_file" main debug: removing module "stream_out_standard" main debug: destroying chain done main debug: removing module "stream_out_duplicate" main debug: destroying chain done main debug: removing module "stream_out_transcode" main debug: destroying chain done main debug: starting new item main debug: changing item without a request (current -1/4) main debug: using item 0 main debug: creating new input thread main debug: Creating an input for 'mms://riosoleado.streamguys.org/kjzz?MSWMExt=.asf' main debug: waiting for thread initialization main debug: thread started main debug: thread 4992 (input) created at priority 1 (input/input.c:370) main debug: stream=`transcode' main debug: looking for sout stream module: 1 candidate main debug: stream=`duplicate' main debug: looking for sout stream module: 1 candidate stream_out_duplicate debug: creating 'duplicate' stream_out_duplicate debug: * adding `std{access=file,mux=raw,dst='C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3'}' main debug: stream=`std' main debug: looking for sout stream module: 1 candidate main debug: set config option: sout-standard-access to file main debug: set config option: sout-standard-mux to raw main debug: set config option: sout-standard-dst to C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3 stream_out_standard debug: creating `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' stream_out_standard debug: extension is mp3 stream_out_standard debug: extension -> mux=(null) stream_out_standard debug: using `file/raw://C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3' main debug: looking for sout access module: 1 candidate access_output_file debug: file access output opened (C:\Program Files\SageTV\webfeedencoder\tmp\KJZZ.mp3) main debug: using sout access module "access_output_file" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) stream_out_standard debug: access opened main debug: looking for sout mux module: 1 candidate mux_dummy debug: Dummy/Raw muxer opened mux_dummy: Open main debug: using sout mux module "mux_dummy" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: muxer support adding stream at any time main debug: muxer prefers to wait for all ES before starting to mux stream_out_standard debug: mux opened main debug: using sout stream module "stream_out_standard" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: using sout stream module "stream_out_duplicate" main debug: TIMER module_Need() : 4.000 ms - Total 4.000 ms / 1 intvls (Avg 4.000 ms) main debug: set config option: sout-transcode-acodec to mp3 main debug: set config option: sout-transcode-ab to 128 main debug: set config option: sout-transcode-channels to 2 stream_out_transcode debug: codec audio=mp3 0Hz 2 channels 128Kb/s main debug: using sout stream module "stream_out_transcode" main debug: TIMER module_Need() : 10.000 ms - Total 10.000 ms / 1 intvls (Avg 10.000 ms) main debug: `mms://riosoleado.streamguys.org/kjzz?MSWMExt=.asf' gives access `mms' demux `' path `riosoleado.streamguys.org/kjzz?MSWMExt=.asf' main debug: creating demux: access='mms' demux='' path='riosoleado.streamguys.org/kjzz?MSWMExt=.asf' main debug: looking for access_demux module: 0 candidates main warning: no access_demux module matched "mms" main debug: TIMER module_Need() : 0.000 ms - Total 0.000 ms / 1 intvls (Avg 0.000 ms) main debug: creating access 'mms' path='riosoleado.streamguys.org/kjzz?MSWMExt=.asf' main debug: looking for access module: 1 candidate access_mms debug: waiting for connection... main debug: net: connecting to riosoleado.streamguys.org port 1755 main debug: connection: Resource temporarily unavailable main debug: accepted socket 5272 (from socket 4872) main warning: connection timed out access_mms error: failed to open a connection (tcp) access_mms debug: waiting for connection... main debug: net: connecting to riosoleado.streamguys.org port 1755 main debug: connection: Resource temporarily unavailable main warning: connection timed out access_mms error: failed to open a connection (tcp) access_mms error: cannot connect to server main debug: net: connecting to riosoleado.streamguys.org port 80 main debug: connection: Resource temporarily unavailable main debug: connection succeeded (socket = 5128) access_mms debug: HTTP reply 'HTTP/1.0 200 OK' access_mms debug: stream type = broadcast access_mms error: cannot read data 2 access_mms debug: complete header size=2801 access_mms debug: packet count=4294967295 packet size=2261 access_mms debug: starting stream main debug: net: connecting to riosoleado.streamguys.org port 80 main debug: connection: Resource temporarily unavailable main debug: connection succeeded (socket = 5128) access_mms debug: HTTP reply 'HTTP/1.0 200 OK' access_mms debug: Content-Type: application/x-mms-framed access_mms debug: Server: Cougar/9.5.6001.18281 access_mms debug: Date: Fri, 13 Nov 2009 23:21:26 GMT access_mms debug: Pragma: no-cache, client-id=177860956, xResetStrm=1, features="broadcast", AccelBW=0, AccelDuration=0, Speed=1.000 access_mms debug: Cache-Control: no-cache access_mms debug: Last-Modified: Fri, 13 Nov 2009 23:21:26 GMT access_mms debug: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile access_mms debug: Connection: keep-alive main debug: using access module "access_mms" main debug: TIMER module_Need() : 10954.001 ms - Total 10954.001 ms / 1 intvls (Avg 10954.000 ms) main debug: Using AStream*Stream main debug: pre-buffering... main debug: received first data for our buffer main debug: pre-buffering done 13566 bytes in 1s - 11 kbytes/s main debug: creating demux: access='mms' demux='' path='riosoleado.streamguys.org/kjzz?MSWMExt=.asf' main debug: looking for demux module: 59 candidates asf debug: found object guid: 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:2751 asf debug: read "header object" subobj:7, reserved1:1, reserved2:2 asf debug: found object guid: 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 size:44 asf debug: read "stream bitrate properties object" asf debug: - stream=1 bitrate=20635 asf debug: - stream=2 bitrate=48760 asf debug: - stream=3 bitrate=3230 asf debug: found object guid: 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104 asf debug: read "file properties object" file_id:0x9e45f93e-0x52cc-0x4c52-0xa4246ea116abdff2 file_size:2801 creation_date:129022418245170000 data_packets_count:4294967295 play_duration:0 send_duration:0 preroll:3064 flags:9 min_data_packet_size:2261 max_data_packet_size:2261 max_bitrate:23865 asf debug: found object guid: 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:2049 asf debug: read "header extension object" reserved1:0xabd3d211-0xa9ba-0x11cf-0x8ee600c00c205365 reserved2:6 header_extension_size:2003 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: 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88 asf debug: read "extended stream properties object": asf debug: - start=0 end=0 asf debug: - data bitrate=20000 buffer=2176 initial fullness=0 asf debug: - alternate data bitrate=20000 buffer=2176 initial fullness=0 asf debug: - maximum object size=1280 asf debug: - flags=0x0 asf debug: - stream number=1 language=0 asf debug: - average time per frame=0 asf debug: - stream name count=0 asf debug: - payload extension system count=0 asf debug: found object guid: 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:202 asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:114 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:28 error_correction_data_length:8 flags:0x2 stream_number:2 asf debug: read "extended stream properties object": asf debug: - start=0 end=0 asf debug: - data bitrate=48024 buffer=3064 initial fullness=0 asf debug: - alternate data bitrate=48024 buffer=3064 initial fullness=0 asf debug: - maximum object size=2230 asf debug: - flags=0x0 asf debug: - stream number=2 language=0 asf debug: - average time per frame=0 asf debug: - stream name count=0 asf debug: - payload extension system count=0 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=1500 buffer=3000 initial fullness=0 asf debug: - alternate data bitrate=1500 buffer=3000 initial fullness=0 asf debug: - maximum object size=0 asf debug: - flags=0x0 asf debug: - stream number=3 language=0 asf debug: - average time per frame=0 asf debug: - stream name count=0 asf debug: - payload extension system count=0 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:218 asf debug: read "metadata object" 4 entries asf debug: - IsVBR=0 asf debug: - DeviceConformanceTemplate=L2 asf debug: - IsVBR=0 asf debug: - DeviceConformanceTemplate=L2 asf debug: found object guid: 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 size:1296 asf warning: unknown asf object (not loaded) asf debug: found object guid: 0xa08649cf-0x4775-0x4670-0x8a166e35357566cd size:46 asf debug: read "advanced mutual exclusion object" asf debug: - stream=1 asf debug: - stream=2 asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:114 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:28 error_correction_data_length:8 flags:0x1 stream_number:1 asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:78 asf debug: read "stream Properties object" stream_type:0x59dacfc0-0x59e6-0x11d0-0xa3ac00a0c90348f6 error_correction_type:0x20fb5700-0x5b55-0x11cf-0xa8fd00805f5c442b time_offset:0 type_specific_data_length:0 error_correction_data_length:0 flags:0x3 stream_number:3 asf debug: found object guid: 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 size:162 asf debug: read "extended content description object" asf debug: - 'WMFSDKVersion' = '9.00.00.3267' asf debug: - 'WMFSDKNeeded' = '0.0.0.0000' asf debug: - 'IsVBR' = 'false' asf debug: found object guid: 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:170 asf debug: read "codec list object" reserved_guid:0x86d15241-0x311d-0x11d0-0xa3a400a0c90348f6 codec_entries_count:1 asf debug: - codec[0] audio name:"Windows Media Audio 9" description:" 48 kbps, 44 kHz, stereo 1-pass CBR" information_length:2 asf debug: found object guid: 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:50 asf debug: read "data object" file_id:0x9e45f93e-0x52cc-0x4c52-0xa4246ea116abdff2 total data packet:0 reserved:257 asf debug: + 'Unknown' GUID 0x0-0x0-0x0-0x0000000000000000 size:0pos:0 asf debug: + 'Header' GUID 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:2751pos:0 asf debug: | + 'Stream Bitrate Properties' GUID 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 size:44pos:30 asf debug: | + 'File Properties' GUID 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104pos:74 asf debug: | + 'Header Extension' GUID 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:2049pos:178 asf debug: | | + 'Language List' GUID 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 size:39pos:224 asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88pos:263 asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:202pos:351 asf debug: | | + 'Extended Stream Properties' GUID 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a size:88pos:553 asf debug: | | + 'Unknown' GUID 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 size:26pos:641 asf debug: | | + 'Metadata' GUID 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca size:218pos:667 asf debug: | | + 'Padding' GUID 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 size:1296pos:885 asf debug: | | + 'Advanced Mutual Exclusion' GUID 0xa08649cf-0x4775-0x4670-0x8a166e35357566cd size:46pos:2181 asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:114pos:2227 asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:78pos:2341 asf debug: | + 'Extended content description' GUID 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 size:162pos:2419 asf debug: | + 'Codec List' GUID 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:170pos:2581 asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:114pos:439 asf debug: + 'Data' GUID 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:50pos:2751 asf debug: found 3 streams asf debug: ignoring not selected stream(ID:1) (by access) asf debug: ignoring not selected stream(ID:3) (by access) main debug: selecting program id=0 asf debug: added new audio stream(codec:0x161,ID:2) main debug: using demux module "asf" main debug: TIMER module_Need() : 3.000 ms - Total 3.000 ms / 1 intvls (Avg 3.000 ms) main debug: looking for a subtitle file in C:\Program Files\SageTV\webfeedencoder\wrapper\ main debug: looking for packetizer module: 18 candidates main debug: using packetizer module "packetizer_copy" main debug: TIMER module_Need() : 22.000 ms - Total 22.000 ms / 1 intvls (Avg 22.000 ms) main debug: stream out mode -> no decoder thread main debug: starting in sync mode access_mms warning: unimplemented query in control main debug: `mms://riosoleado.streamguys.org/kjzz?MSWMExt=.asf' successfully opened packetizer_copy debug: need dts > 0 main debug: control type=1 main debug: adding a new sout input (sout_input:008F6F00) stream_out_transcode debug: creating audio transcoding from fcc=`wma2' to fcc=`mp3 ' main debug: looking for decoder module: 34 candidates avcodec debug: libavcodec initialized (interface 3408896 ) avcodec warning: Physical channel configuration not set : guessing avcodec debug: ffmpeg codec (Windows Media Audio 2) 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: looking for encoder module: 11 candidates avcodec debug: libavcodec already initialized avcodec debug: found encoder MPEG Audio layer 1/2/3 main debug: using encoder module "avcodec" main debug: TIMER module_Need() : 12.000 ms - Total 12.000 ms / 1 intvls (Avg 12.000 ms) stream_out_duplicate debug: duplicated a new stream codec=mpga (es=0 group=0) main debug: adding a new input mux_dummy debug: adding input stream_out_duplicate debug: - added for output 0 main debug: switching to async mode main debug: incoming request - stopping current input main debug: waitpipe: object killed main debug: dying input main debug: socket 5128 polling interrupted access_mms error: cannot read data 2 main debug: deleting item `http://kjzz-low.streamguys.org:80/' main debug: control type=0 main debug: control: stopping input main debug: deleting item `http://kjzz.streamguys.org:80/' main debug: deleting item `mms://216.246.51.219:80/kjzz?MSWMExt=.asf' main debug: deleting item `mms://riosoleado.streamguys.org/kjzz?MSWMExt=.asf' main debug: deleting item `http://kjzz-low.streamguys.org:80/' main debug: deleting item `http://kjzz.streamguys.org:80/' main debug: deleting item `mms://216.246.51.219:80/kjzz?MSWMExt=.asf' main: stopping playback main debug: deleting item `mms://riosoleado.streamguys.org/kjzz?MSWMExt=.asf' asf debug: free asf object 0x7bf875ce-0x468d-0x11d1-0x8d82006097c9a2b2 asf debug: free asf object 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 main debug: removing all interfaces asf debug: free asf object 0x7c4346a9-0xefe0-0x4bfc-0xb229393ede415c85 main debug: waitpipe: object killed main debug: thread ended asf debug: free asf object 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a asf debug: free asf object 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a asf debug: free asf object 0x14e6a5cb-0xc672-0x4332-0x8399a96952065b5a main debug: thread times: real 1m1.734375s, kernel 0m0.000000s, user 0m0.000000s asf warning: unknown asf object 0x26f18b5d-0x4584-0x47ec-0x9f5f0e651f0452c9 main debug: thread 4860 joined (interface/interface.c:188) asf debug: free asf object 0xc5f8cbea-0x5baf-0x4877-0x8467aa8c44fa4cca asf warning: unknown asf object 0x1806d474-0xcadf-0x4509-0xa4ba9aabcb96aae8 asf debug: free asf object 0xa08649cf-0x4775-0x4670-0x8a166e35357566cd main debug: free asf object 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 main debug: removing module "rc" asf debug: free asf object 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 asf debug: free asf object 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 asf debug: free asf object 0xd2d0a440-0xe307-0x11d2-0x97f000a0c95ea850 asf debug: free asf object 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 asf debug: free asf object 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 main debug: thread ended asf debug: free asf object 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c asf debug: free asf object 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c main debug: removing module "packetizer_copy" main debug: thread times: real 1m1.750000s, kernel 0m0.000000s, user 0m0.000000s main debug: killing decoder fourcc `wma2', 0 PES in FIFO main debug: thread 4792 joined (interface/interface.c:188) main debug: removing a sout input (sout_input:008F6F00) main debug: removing module "hotkeys" main debug: TIMER encoding audio frame : 4.000 ms - Total 2090.000 ms / 534 intvls (Avg 3.914 ms) avcodec debug: ffmpeg codec (Windows Media Audio 2) stopped main debug: removing module "avcodec" main debug: removing module "avcodec" mux_dummy debug: removing input main warning: no more input streams for this mux main debug: Program doesn't contain anymore ES main debug: removing module "asf" access_mms debug: closing stream main debug: removing module "access_mms" main debug: thread ended main debug: thread ended main debug: thread times: real 1m1.812500s, kernel 0m0.031250s, user 0m0.000000s main debug: thread 4768 joined (interface/interface.c:188) -- logger module stopped --