[mythtv-users] AFD Error: avformat err(-1) on av_open_input_file call. Can't play 60 minutes

Marc MERLIN marc_mtv at merlins.org
Sun Jul 19 21:01:59 UTC 2009


On Thu, Jul 16, 2009 at 06:28:22PM -0700, Marc MERLIN wrote:
> I've been trying to debug this problem, but I'm getting nowhere.
> 
> 60 minutes for the last 3 weeks (in HD) is recording, I can play the file
> with mplayer, but I cannot play it with myfrontend
> 1) it doesn't play
> 2) it wedges mythfrontend so that I have to restart it so that I can't play
>    other programs that otherwise play fine.

I did some more debugging on this, but still no luck.

I upgraded my laptop (i.e. a different frontend) to 0.21.0+fixes19961-0ubuntu8
Same problem on that frontend with the dreaded 60 minutes video on an intel chipset instead
of nvidia on my myth backend/frontend I first tried on.

This is a video playing ok:
2009-07-19 13:47:05.785 TV: Attempting to change from None to WatchingPreRecorded
2009-07-19 13:47:06.334 AFD: Opened codec 0x9f69b00, id(MPEG2VIDEO) type(Video)
2009-07-19 13:47:06.334 AFD: codec AC3 has 2 channels
2009-07-19 13:47:06.336 AFD: Opened codec 0x9f1a9a0, id(AC3) type(Audio)
2009-07-19 13:47:06.338 Opening audio device 'default'. ch 2(2) sr 48000
2009-07-19 13:47:06.338 Opening ALSA audio device 'default'.
2009-07-19 13:47:06.452 VideoOutputXv: XVideo Adaptor Name: 'Radeon Textured Video'
2009-07-19 13:47:06.490 VideoOutputXv: Ack! Disabling ChromaKey OSD
                        We can't use ChromaKey OSD if chromakeying is not supported!
2009-07-19 13:47:06.504 OSD Theme Dimensions W: 640 H: 480
2009-07-19 13:47:06.777 Realtime priority would require SUID as root.
2009-07-19 13:47:06.777 TV: Changing from None to WatchingPreRecorded
2009-07-19 13:47:06.788 Video timing method: DRM


60 minutes:
2009-07-19 13:50:15.999 TV: Attempting to change from None to WatchingPreRecorded
2009-07-19 13:50:16.430 Tuning to 'MPEG Program 1' pnum: 0x1 without CRC check on PMT
2009-07-19 13:50:16.607 mpegts_read_header: could not find any PMT's
2009-07-19 13:50:16.607 AFD Error: avformat err(-1) on av_open_input_file call.
2009-07-19 13:50:16.607 Couldn't open decoder for: myth://192.168.205.61:6543/2051_20090628190000.mpg
2009-07-19 13:50:16.608 TV Error: StartPlayer(): NVP is not playing after 20000 msec
2009-07-19 13:50:16.609 TV: Changing from None to WatchingPreRecorded
2009-07-19 13:50:16.610 TV: Attempting to change from WatchingPreRecorded to None
2009-07-19 13:50:16.645 TV: Changing from WatchingPreRecorded to None


more debugging doesn't really say:
2009-07-19 13:51:39.683 write -> 15 693     QUERY_CHECKFILE[]:[]1[]:[]60 Minutes[]:[][]:[]Inside the secretive "Supermax" prison where the nation's most dangerous criminals are held; drug violence in Mexico; basketball player LeBron James earns tens of millions of dollars a year.[]:[]Newsmagazine[]:[]2051[]:[]5.1[]:[]KPIX DT[]:[]KPIX DT[]:[]myth://192.168.205.61:6543/2051_20090621190000.mpg[]:[]1[]:[]2111580144[]:[]1245636000[]:[]1245639600[]:[]0[]:[]0[]:[]733944[]:[]myth[]:[]0[]:[]0[]:[]0[]:[]1[]:[]-3[]:[]69[]:[]0[]:[]15[]:[]1[]:[]1245636000[]:[]1245639600[]:[]0[]:[]4[]:[]Default[]:[]0[]:[][]:[]EP00000035[]:[]EP000000350915[]:[]1245639601[]:[]0.000000[]:[]2009-06-21[]:[]1[]:[]Default[]:[]427[]:[]0[]:[]Default[]:[]1[]:[]1[]:[]1
2009-07-19 13:51:39.683 read  <- 15 63      1[]:[]/var/spool/mythtv/recording.myth3/2051_20090621190000.mpg
(...)
2009-07-19 13:51:39.782 MythEvent: PLAYBACK_START gandalf.merlins.org
2009-07-19 13:51:39.782 write -> 15 23      GET_FREE_RECORDER_COUNT
2009-07-19 13:51:39.784 read  <- 15 1       6
(...)
2009-07-19 13:51:40.182 Read(): reqd=327680, rcvd=327680, rept=327680, error=0
2009-07-19 13:51:40.183 write -> 18 50      QUERY_FILETRANSFER 27[]:[]REQUEST_BLOCK[]:[]327680
2009-07-19 13:51:40.189 read  <- 18 6       327680
2009-07-19 13:51:40.189 Read(): reqd=327680, rcvd=327680, rept=327680, error=0
2009-07-19 13:51:40.189 mpegts_read_header: could not find any PMT's
2009-07-19 13:51:40.189 AFD Error: avformat err(-1) on av_open_input_file call.
2009-07-19 13:51:40.189 Couldn't open decoder for: myth://192.168.205.61:6543/2051_20090621190000.mpg
2009-07-19 13:51:40.189 TV: StartPlayer(): took 347 ms to start player.
2009-07-19 13:51:40.189 TV Error: StartPlayer(): NVP is not playing after 20000 msec
2009-07-19 13:51:40.189 write -> 15 53      MESSAGE[]:[]COMMFLAG_REQUEST 2051 2009-06-21T19:00:00
2009-07-19 13:51:40.190 MythSocket(b3247310:14): socket is readable
2009-07-19 13:51:40.190 MythSocket(b3247310:14): cb->readyRead()


And the video still plays fine with mplayer:
myth:~$ mplayer -v /var/spool3/mythtv/recording.myth3/2051_20090628190000.mpg
MPlayer 1.0rc2-4.2.3 (C) 2000-2007 MPlayer Team
CPU: Intel(R) Core(TM)2 Duo CPU     E8400  @ 3.00GHz (Family: 6, Model: 23, Stepping: 6)
CPUflags:  MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 1 SSE2: 1
Compiled with runtime CPU detection.
get_path('codecs.conf') -> '/home/mythtv/.mplayer/codecs.conf'
Reading /home/mythtv/.mplayer/codecs.conf: 115 audio & 238 video codecs
Configuration: --enable-runtime-cpudetection --target=i586-linux --prefix=/usr --confdir=/etc/mpl
ayer --mandir=/usr/share/man --win32codecsdir=/usr/lib/win32 --enable-largefiles --disable-libdvd
css-internal --enable-smb --enable-ftp --enable-cdparanoia --enable-radio --enable-lirc --enable-
joystick --enable-xf86keysym --disable-tremor-internal --enable-liba52 --enable-musepack --enable
-speex --enable-libvorbis --enable-mad --enable-mp3lib --enable-theora --enable-libdv --enable-li
bmpeg2 --enable-tv-v4l2 --enable-alsa --enable-ossaudio --enable-esd --enable-arts --enable-pulse
 --enable-nas --enable-xinerama --enable-menu --enable-xv --enable-vm --enable-gl --enable-xmga -
-enable-mga --enable-3dfx --enable-tdfxfb --enable-sdl --enable-aa --enable-caca --enable-dxr3 --
enable-xvmc --with-xvmclib=XvMCW --enable-ggi --enable-fbdev --enable-freetype --enable-gif --ena
ble-png --enable-jpeg --enable-liblzo --enable-fribidi --enable-ladspa --enable-gui --enable-menc
oder    
CommandLine: '-v' '/var/spool3/mythtv/recording.myth3/2051_20090628190000.mpg'
init_freetype
Using MMX (with tiny bit MMX2) Optimized OnScreenDisplay
get_path('fonts') -> '/home/mythtv/.mplayer/fonts'
Using nanosleep() timing
get_path('input.conf') -> '/home/mythtv/.mplayer/input.conf'
Can't open input config file /home/mythtv/.mplayer/input.conf: No such file or directory
Parsing input config file /etc/mplayer/input.conf
Input config file /etc/mplayer/input.conf parsed: 81 binds
Setting up LIRC support...
get_path('2051_20090628190000.mpg.conf') -> '/home/mythtv/.mplayer/2051_20090628190000.mpg.conf'
        
Playing /var/spool3/mythtv/recording.myth3/2051_20090628190000.mpg.
get_path('sub/') -> '/home/mythtv/.mplayer/sub/'
[file] File size is 6676795936 bytes
STREAM: [file] /var/spool3/mythtv/recording.myth3/2051_20090628190000.mpg
STREAM: Description: File
STREAM: Author: Albeu
STREAM: Comment: based on the code from ??? (probably Arpi)
LAVF_check: MPEG2 transport stream format
Checking for YUV4MPEG2
ASF_check: not ASF guid!
Checking for NuppelVideo
Checking for REAL
Checking for SMJPEG
Searching demuxer type for filename /var/spool3/mythtv/recording.myth3/2051_20090628190000.mpg ex
t: .mpg 
Checking for Nullsoft Streaming Video
Checking for MOV
Checking for VIVO
header block 1 size: 8
AVS: avs_check_file - attempting to open file /var/spool3/mythtv/recording.myth3/2051_20090628190
000.mpg 
AVS: File is too big, aborting...
Checking for PVA
Checking for MPEG-TS...
TRIED UP TO POSITION 0, FOUND 47, packet_size= 188, SEEMS A TS? 1
GOOD CC: 32, BAD CC: 0
TS file format detected.
DEMUX OPEN, AUDIO_ID: -1, VIDEO_ID: -1, SUBTITLE_ID: -2,
Checking for MPEG-TS...
TRIED UP TO POSITION 0, FOUND 47, packet_size= 188, SEEMS A TS? 1
GOOD CC: 32, BAD CC: 0
PROBING UP TO 0, PROG: 0
COLLECT_SECTION, start: 64, size: 184, collected: 0
SKIP: 0+1, TID: 0, TLEN: 13, COLLECTED: 184
PARSE_PAT: section_len: 13, section 0/0
PROG: 1 (1-th of 1), PMT: 49
COLLECT_SECTION, start: 64, size: 184, collected: 0
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 0, TLEN: 13, COLLECTED: 184
PARSE_PAT: section_len: 13, section 0/0
PROG: 1 (1-th of 1), PMT: 49
VIDEO MPEG2(pid=2048) AUDIO A52(pid=2050) NO SUBS (yet)!  PROGRAM N. 0
==> Found video stream: 0
        
ADDED VIDEO PID 2048, type: 10000002 stream n. 0
==> Found audio stream: 0
        
ADDED AUDIO PID 2050, type: 2000 stream n. 0
Opened TS demuxer, audio: 2000(pid 0), video: 10000002(pid 0)...POS=0, PROBE=0
Searching for sequence header... COLLECT_SECTION, start: 64, size: 184, collected: 0
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
COLLECT_SECTION, start: 64, size: 184, collected: 0
SKIP: 0+1, TID: 0, TLEN: 13, COLLECTED: 184
PARSE_PAT: section_len: 13, section 0/0
PROG: 1 (1-th of 1), PMT: 49
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
==> Found audio stream: 1
        
ADDED AUDIO PID 2049, type: 2000 stream n. 1
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 0, TLEN: 13, COLLECTED: 184
PARSE_PAT: section_len: 13, section 0/0
PROG: 1 (1-th of 1), PMT: 49
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
OK!     
VIDEO:  MPEG2  1920x1080  (aspect 3)  29.970 fps  38810.4 kbps (4851.3 kbyte/s)
[V] filefmt:29  fourcc:0x10000002  size:1920x1080  fps:29.97  ftime:=0.0334
get_path('sub/') -> '/home/mythtv/.mplayer/sub/'
X11 opening display: :0
vo: X11 color mask:  FFFFFF  (R:FF0000 G:FF00 B:FF)
vo: X11 running at 1920x1080 with depth 24 and 32 bpp (":0" => local display)
[x11] Detected wm supports layers.
[x11] Detected wm supports NetWM.
[x11] Detected wm supports ABOVE state.
[x11] Detected wm supports BELOW state.
[x11] Detected wm supports FULLSCREEN state.
[x11] Detected wm supports STAYS_ON_TOP state.
[x11] Current fstype setting honours LAYER FULLSCREEN STAYS_ON_TOP ABOVE BELOW X atoms
xscreensaver_disable: Could not find XScreenSaver window.
gnome_screensaver_control()[xv common] Drawing colorkey manually.
[xv common] Using colorkey from Xv (0x0101fe).
[xv common] Maximum source image dimensions: 1920x1088
==========================================================================
Opening video decoder: [mpegpes] MPEG 1/2 Video passthrough
VDec: vo config request - 1920 x 1080 (preferred colorspace: Mpeg PES)
Trying filter chain: vo
Could not find matching colorspace - retrying with -vf scale...
Opening video filter: [scale]
SwScale params: -1 x -1 (-1=no scaling)
Trying filter chain: scale vo
The selected video_out device is incompatible with this codec.
Try appending the scale filter to your filter list,
e.g. -vf spp,scale instead of -vf spp.
VDecoder init failed :(
Opening video decoder: [libmpeg2] MPEG 1/2 Video decoder libmpeg2-v0.4.0b
Selected video codec: [mpeg12] vfm: libmpeg2 (MPEG-1 or 2 (libmpeg2))
==========================================================================
==========================================================================
Forced audio codec: mad
Trying to force audio codec driver family hwac3...
Opening audio decoder: [hwac3] AC3/DTS pass-through S/PDIF
dec_audio: Allocating 8192 bytes for input buffer.
dec_audio: Allocating 16384 + 65536 = 81920 bytes for output buffer.
No accelerated IMDCT transform found
hwac3: switched to AC3, 192000 bps, 48000 Hz
AUDIO: 48000 Hz, 2 ch, ac3, 192.0 kbit/12.50% (ratio: 24000->192000)
Selected audio codec: [hwac3] afm: hwac3 (AC3 through S/PDIF)
==========================================================================
Building audio filter chain for 48000Hz/2ch/ac3 -> 0Hz/0ch/??...
[libaf] Adding filter dummy
[dummy] Was reinitialized: 48000Hz/2ch/ac3
[dummy] Was reinitialized: 48000Hz/2ch/ac3
alsa-init: requested format: 48000 Hz, 2 channels, 100
alsa-init: using ALSA 1.0.17a
alsa-spdif-init: playing AC3, 2 channels
alsa-init: using device iec958
alsa-init: pcm opened in blocking mode
alsa-init: chunksize set to 1024
alsa-init: fragcount=16
alsa-init: got buffersize=65536
alsa-init: got period size 1024
alsa: 48000 Hz/2 channels/4 bpf/65536 bytes buffer/Signed 16 bit Little Endian
AO: [alsa] 48000Hz 2ch ac3 (1 bytes per sample)
AO: Description: ALSA-0.9.x-1.x audio output
AO: Author: Alex Beregszaszi, Zsolt Barat <joy at streamminister.de>
AO: Comment: under developement
Building audio filter chain for 48000Hz/2ch/ac3 -> 48000Hz/2ch/ac3...
[dummy] Was reinitialized: 48000Hz/2ch/ac3
[dummy] Was reinitialized: 48000Hz/2ch/ac3
Starting playback...
VDec: vo config request - 1920 x 1080 (preferred colorspace: Planar YV12)
Trying filter chain: vo
VDec: using Planar YV12 as output csp (no 0)
Movie-Aspect is 1.78:1 - prescaling to correct movie aspect.
VO Config (1920x1080->1920x1080,flags=5,'MPlayer',0x32315659)
VO: [xv] 1920x1080 => 1920x1080 Planar YV12  [fs] [zoom]
VO: Description: X11/Xv
VO: Author: Gerd Knorr <kraxel at goldbach.in-berlin.de> and others
Xvideo image format: 0x32595559 (YUY2) packed
Xvideo image format: 0x32315659 (YV12) planar
Xvideo image format: 0x30323449 (I420) planar
Xvideo image format: 0x59565955 (UYVY) packed
Xvideo image format: 0x434d5658 (XVMC) planar
[x11] original window layer is 4.
[x11] Layered style stay on top (layer 10).
using Xvideo port 81 for hw scaling
[xv-fs] dx: 0 dy: 0 dw: 1920 dh: 1080
[xv] dx: 0 dy: 0 dw: 1920 dh: 1080
[x11] Layered style stay on top (layer 10).
*** [vo] Allocating mp_image_t, 1920x1088x12bpp YUV planar, 3133440 bytes
[xv] dx: 0 dy: 0 dw: 1920 dh: 1080
[xv-fs] dx: 0 dy: 0 dw: 1920 dh: 1080
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 2, TLEN: 196, COLLECTED: 184
COLLECT_SECTION, start: 64, size: 184, collected: 184
SKIP: 0+1, TID: 0, TLEN: 13, COLLECTED: 184
PARSE_PAT: section_len: 13, section 0/0
PROG: 1 (1-th of 1), PMT: 49
*** [vo] Allocating mp_image_t, 1920x1088x12bpp YUV planar, 3133440 bytes
*** [vo] Allocating mp_image_t, 1920x1088x12bpp YUV planar, 3133440 bytes
Unicode font: 4861 glyphs.
COLLECT_SECTION, start: 64, size: 184, collected: 184
(...)

I give up, any other ideas?

Thanks,
Marc
-- 
"A mouse is a device used to point at the xterm you want to type in" - A.S.R.
Microsoft is to operating systems & security ....
                                      .... what McDonalds is to gourmet cooking
Home page: http://marc.merlins.org/  


More information about the mythtv-users mailing list