[mythtv-users] Re: mythstream won't stream

[Chase Douglas]

I added -vvv to the vlc lines in mythstreamtv.sh. The resulting log (a bit long
even after I cut out 292,000 lines of "ps demuxer warning: garbage at input")
follows below. What is going wrong?

Thanks!

Starting Stream of /video/recordings/1038_20050126220000_20050126223000.nuv
VLC media player 0.8.1 Janus
[00000001] main vlc debug: opening config file /home/httpd/.vlc/vlcrc
[00000001] main vlc warning: config file /home/httpd/.vlc/vlcrc does not exist yet
[00000001] main vlc debug: checking builtin modules
[00000001] main vlc debug: checking plugin modules
[00000001] main vlc debug: loading plugins cache file
/home/httpd/.vlc/cache/plugins-04041e.dat
[00000001] main vlc debug: recursively browsing `modules'
[00000001] main vlc debug: recursively browsing `/usr/local/lib/vlc'
[00000001] main vlc debug: recursively browsing `plugins'
[00000001] main vlc debug: module bank initialized, found 168 modules
[00000001] main vlc debug: opening config file /home/httpd/.vlc/vlcrc
[00000001] main vlc warning: config file /home/httpd/.vlc/vlcrc does not exist yet
[00000000] main root debug: VLC media player - version 0.8.1 Janus - (c)
1996-2004 VideoLAN
[00000000] main root debug: libvlc was configured with ./configure --disable-x11
--disable-xvideo --disable-gtk --disable-sdl --enable-ffmpeg
--with-ffmpeg-mp3lame --with-ffmpeg-zlib --enable-mad --enable-libdvbpsi
--enable-a52 --disable-dvdplay --enable-dvdnav --enable-vorbis --enable-ogg
--enable-theora --enable-mkv --enable-freetype --disable-cddax --disable-vcdx
--enable-speex --enable-flac --enable-goom --enable-livedotcom --enable-caca
--disable-skins2 --enable-modplug --enable-debug --enable-gpl --enable-mp3lame
--enable-pp --with-ffmpeg-tree=/home/mythtv/ffmpeg-0.4.9-pre1
[00000001] main vlc debug: translation test: code is "C"
[00000001] main vlc debug: opening config file /home/httpd/.vlc/vlcrc
[00000001] main vlc warning: config file /home/httpd/.vlc/vlcrc does not exist yet
[00000001] main vlc debug: checking builtin modules
[00000001] main vlc debug: checking plugin modules
[00000001] main vlc debug: loading plugins cache file
/home/httpd/.vlc/cache/plugins-04041e.dat
[00000001] main vlc debug: recursively browsing `modules'
[00000001] main vlc debug: recursively browsing `/usr/local/lib/vlc'
[00000001] main vlc debug: recursively browsing `plugins'
[00000001] main vlc debug: module bank initialized, found 168 modules
[00000001] main vlc debug: opening config file /home/httpd/.vlc/vlcrc
[00000001] main vlc warning: config file /home/httpd/.vlc/vlcrc does not exist yet
[00000001] main vlc debug: CPU has capabilities 486 586 MMX 3DNow! MMXEXT SSE FPU 
[00000001] main vlc debug: looking for memcpy module
[00000001] main vlc debug: probing 4 candidates
[00000010] main module debug: using memcpy module "memcpymmxext"
[00000372] main playlist debug: creating group Normal with id 1 at position 0
[00000372] main playlist debug: waiting for thread completion
[00000372] main playlist debug: thread 16386 (playlist) created at priority 0
(src/playlist/playlist.c:107)
[00000373] main interface debug: looking for interface module
[00000373] main interface debug: probing 1 candidate
[00000217] main module debug: using interface module "hotkeys"
[00000373] main interface debug: interface initialized
[00000373] main interface debug: thread 32771 (interface) created at priority 0
(src/interface/interface.c:209)
[00000372] main playlist debug: adding playlist item
`/video/recordings/1038_20050126220000_20050126223000.nuv' (
/video/recordings/1038_20050126220000_20050126223000.nuv )
[00000374] main interface debug: looking for interface module
[00000374] main interface debug: probing 2 candidates
[00000374] http interface debug: base :8002
[00000374] main interface: creating httpd
[00000376] main private debug: thread 49156 (httpd host thread) created at
priority 0 (src/misc/httpd.c:1170)
[00000374] http interface debug: dir=/usr/local/share/vlc/http
[00000374] http interface debug: dir=/usr/local/share/vlc/http/vlm
[00000374] http interface debug: file=/usr/local/share/vlc/http/vlm/edit.html
(url=/vlm/edit.html)
[00000374] http interface debug: file=/usr/local/share/vlc/http/vlm/index.html
(url=/vlm/)
[00000374] http interface debug: redir=/vlm -> /vlm/
[00000374] http interface debug: file=/usr/local/share/vlc/http/vlm/new.html
(url=/vlm/new.html)
[00000374] http interface debug: dir=/usr/local/share/vlc/http/admin
[00000374] http interface debug: find .access in dir=/usr/local/share/vlc/http/admin
[00000374] http interface debug: using user=admin password=admin (read=11)
[00000374] http interface debug:
file=/usr/local/share/vlc/http/admin/dboxfiles.html (url=/admin/dboxfiles.html)
[00000374] http interface debug: file=/usr/local/share/vlc/http/admin/index.html
(url=/admin/)
[00000374] http interface debug: redir=/admin -> /admin/
[00000374] http interface debug:
file=/usr/local/share/vlc/http/admin/browse.html (url=/admin/browse.html)
[00000374] http interface debug: file=/usr/local/share/vlc/http/style.css
(url=/style.css)
[00000374] http interface debug: file=/usr/local/share/vlc/http/index.html (url=/)
[00000374] http interface debug: redir= -> /
[00000374] http interface debug: file=/usr/local/share/vlc/http/info.html
(url=/info.html)
[00000218] main module debug: using interface module "http"
[00000374] main interface debug: interface initialized
[00000372] main playlist debug: creating new input thread
[00000377] main input debug: set input option: sout to
#transcode{vcodec=DIV3,acodec=mp3,vb=500,ab=128,scale=1}:std{access=mmsh,mux=asfh,url=:8001}
[00000377] main input debug: waiting for thread completion
[00000378] main stream output debug: stream=`transcode'
[00000379] main private debug: looking for sout stream module
[00000379] main private debug: probing 1 candidate
[00000378] main stream output debug: stream=`std'
[00000381] main private debug: looking for sout stream module
[00000381] main private debug: probing 1 candidate
[00000377] main input debug: thread 65541 (input) created at priority 0
(src/input/input.c:228)
[00000374] main interface debug: thread 81926 (manager) created at priority 0
(src/interface/interface.c:194)
[00000381] main private debug: set sout option: sout-standard-access to mmsh
[00000381] main private debug: set sout option: sout-standard-mux to asfh
[00000381] main private debug: set sout option: sout-standard-url to :8001
[00000381] stream_out_standard private debug: creating `mmsh/asfh://:8001'
[00000381] stream_out_standard private debug: using `mmsh/asfh://:8001'
[00000382] main private debug: looking for sout access module
[00000382] main private debug: probing 1 candidate
[00000383] main private debug: thread 98311 (httpd host thread) created at
priority 0 (src/misc/httpd.c:1170)
[00000221] main module debug: using sout access module "access_output_http"
[00000381] stream_out_standard private debug: access opened
[00000384] main private debug: looking for sout mux module
[00000384] main private debug: probing 1 candidate
[00000384] mux_asf private debug: Asf muxer opened
[00000384] mux_asf private debug: creating asf stream to be used with mmsh
[00000384] mux_asf private debug: meta data: title='' author='' copyright=''
comment='' rating=''
[00000183] main module debug: using sout mux module "mux_asf"
[00000378] main stream output debug: muxer support adding stream at any time
[00000378] main stream output debug: muxer prefers waiting for all ES before
starting muxing
[00000381] stream_out_standard private debug: mux opened
[00000328] main module debug: using sout stream module "stream_out_standard"
[00000379] main private debug: set sout option: sout-transcode-vcodec to DIV3
[00000379] main private debug: set sout option: sout-transcode-acodec to mp3
[00000379] main private debug: set sout option: sout-transcode-vb to 500
[00000379] main private debug: set sout option: sout-transcode-ab to 128
[00000379] main private debug: set sout option: sout-transcode-scale to 1
[00000379] stream_out_transcode private debug: codec audio=mp3  0Hz 0 channels
128Kb/s
[00000379] stream_out_transcode private debug: codec video=DIV3 0x0 scaling:
1.000000 500kb/s
[00000330] main module debug: using sout stream module "stream_out_transcode"
[00000377] main input debug:
`/video/recordings/1038_20050126220000_20050126223000.nuv' gives access `' demux
`' path `/video/recordings/1038_20050126220000_20050126223000.nuv'
[00000377] main input debug: demux2_New: access='' demux=''
path='/video/recordings/1038_20050126220000_20050126223000.nuv'
[00000385] main demuxer debug: looking for access_demux module
[00000385] main demuxer debug: probing 1 candidate
[00000377] main input debug: access2_New: access=''
path='/video/recordings/1038_20050126220000_20050126223000.nuv'
[00000386] main access debug: looking for access2 module
[00000386] main access debug: probing 4 candidates
[00000386] vcd access debug: trying .cue file:
/video/recordings/1038_20050126220000_20050126223000.cue
[00000386] vcd access warning: could not open
/video/recordings/1038_20050126220000_20050126223000.nuv
[00000386] access_file access debug: opening file
`/video/recordings/1038_20050126220000_20050126223000.nuv'
[00000291] main module debug: using access2 module "access_file"
[00000387] main private debug: pre buffering
[00000387] main private debug: received first data for our buffer
[00000387] main private debug: prebuffering done 1408981 bytes in 0s - 21351
kbytes/s
[00000377] main input debug: demux2_New: access='' demux=''
path='/video/recordings/1038_20050126220000_20050126223000.nuv'
[00000388] main demuxer debug: looking for demux2 module
[00000388] main demuxer debug: probing 31 candidates
[00000388] mp4 demuxer warning: MP4 plugin discarded (not a valid file)
[00000388] avi demuxer warning: avi module discarded (invalid header)
[00000388] asf demuxer warning: ASF plugin discarded (not a valid file)
[00000388] flac demuxer warning: flac module discarded (no startcode)
[00000388] mpgv demuxer warning: ES module discarded (no startcode)
[00000388] main demuxer debug: looking for id3 module
[00000388] main demuxer debug: probing 2 candidates
[00000388] id3tag demuxer debug: checking for ID3 tag
[00000261] main module debug: using id3 module "id3tag"
[00000261] main module debug: unlocking module "id3tag"
[00000388] aac demuxer warning: AAC module discarded
[00000388] sap demuxer warning: SDP (UDP) module discarded
[00000388] mkv demuxer warning: matroska module discarded (invalid header
0x4d797468)
[00000388] ogg demuxer warning: ogg module discarded (invalid header)
[00000388] real demuxer warning: Real module discarded
[00000388] playlist demuxer warning: old import module discarded: invalid file
[00000388] playlist demuxer warning: pls import module discarded
[00000388] au demuxer warning: AU module discarded
[00000388] ts demuxer warning: TS module discarded
[00000388] nsv demuxer warning: NSV module discarded
[00000388] aiff demuxer warning: AIFF module discarded
[00000388] pva demuxer warning: PVA module discarded
[00000388] ffmpeg demuxer debug: couldn't guess format
[00000388] vobsub demuxer debug: this doesn't seem to be a vobsub file
[00000388] ps demuxer warning: this does not look like an MPEG PS stream,
continuing anyway
[00000283] main module debug: using demux2 module "ps"
[00000377] main input debug: looking for a subtitle file in /video/recordings/
[00000377] main input debug: starting in synch mode
[00000377] main input debug:
`/video/recordings/1038_20050126220000_20050126223000.nuv' sucessfully opened
[00000388] ps demuxer warning: garbage at input
[00000377] main input debug: control type=1
[00000388] ps demuxer warning: garbage at input /* 292,000 lines of this has
[00000388] ps demuxer warning: garbage at input  * been cutout from the log.
[00000388] ps demuxer warning: garbage at input  * It had only been streaming
[00000388] ps demuxer warning: garbage at input  * for a few seconds (<10) */
[00000388] ps demuxer warning: garbage at input
[00000388] ps demuxer warning: garbage at input
[00000388] ps demuxer warning: garbage at input
[00000388] ps demuxer warning: garbage at input
[00000388] ps demuxer warning: garbage at input
[00000388] ps demuxer warning: garbage at input
[00000377] main input debug: EOF reached
[00000377] main input debug: closing input
[00000231] main module debug: unlocking module "mpeg_audio"
[00000389] main packetizer debug: killing decoder fourcc `mpga', 0 PES in FIFO
[00000377] main input warning: Program doesn't contain anymore ES, TODO cleaning ?
[00000283] main module debug: unlocking module "ps"
[00000291] main module debug: unlocking module "access_file"
[00000377] main input warning: destroying sout
[00000379] main private debug: destroying chain... (name=transcode)
[00000381] main private debug: destroying chain... (name=std)
[00000384] mux_asf private debug: Asf muxer closed
[00000382] access_output_http private warning: HTTP sout access cannot seek
[00000183] main module debug: unlocking module "mux_asf"
[00000383] main private debug: httpd_HostDelete
[00000383] main private debug: httpd_HostDelete: host removed from http
[00000383] main private debug: thread 98311 joined (src/misc/httpd.c:1226)
[00000383] main private debug: httpd_HostDelete: host thread joined
[00000382] access_output_http private debug: Close
[00000221] main module debug: unlocking module "access_output_http"
[00000328] main module debug: unlocking module "stream_out_standard"
[00000381] main private debug: destroying chain done
[00000330] main module debug: unlocking module "stream_out_transcode"
[00000379] main private debug: destroying chain done
[00000377] main input debug: thread 65541 joined (src/input/input.c:290)
/usr/share/mythstreamtv/mythstreamtv.sh: line 58:  7789 Terminated             
/usr/local/bin/vlc -vvv -I http --http-host=:8002 --sout-transcode-fps=$FPS
--sout-transcode-deinterlace $1
":sout=#transcode{vcodec=$VCODEC,acodec=$ACODEC,vb=$VB,ab=$AB,scale=$SIZE}:std{access=mmsh,mux=asfh,url=:8001}"
rm: cannot remove `/var/tmp/STREAMSTOP': Operation not permitted