[mythtv-users] Status of ffmpeg 4.4.1

Tue Nov 16 01:53:33 UTC 2021

On Mon, Nov 15, 2021 at 8:06 PM Peter Bennett <pb.mythtv at gmail.com> wrote:

>
> On 11/15/21 5:49 PM, James Abernathy wrote:
> > So here was the sequence.  After testing with ffmpeg-resync with the
> > latest pull, I checked out Master again and git clean, etc. and
> > rebuilt. On that version when I tuned to a station that only broadcast
> > in Stereo, it had the popup in the frontend with the RED X. I forgot
> > the exact message, but figured it was the same as before.
> >
> If you run the frontend with -v libav,audio, the error that was
> appearing with ffmpeg-resync before I fixed it was this
>
> 2021-11-03 14:43:28.698565 E  [ac3_fixed @ 00007fb5939a5c00] Specified
> sample format s16p is invalid or not supported
>
> It seems that s16p was no longer supported in the new ffmpeg version. I
> switched to format FLT (float) to fix it and switched to codec ac3
> instead of ac3_fixed.
>
> I suspect you may be getting some other error.
>
> Peter
>

So I just checked out Master and rebuilt.  I ran the frontend with the
verbose option you suggested.  On Screen it says the AC3 decoder failed to
initialize. Part of the log is below:

2021-11-15 20:47:25.465368 I  Player(0): Opening
'/srv/mythtv/livetv/15003_20211116014724.ts'
2021-11-15 20:47:26.421107 W  [mpegts @ 00007f6445e10140] PES packet size
mismatch
2021-11-15 20:47:26.421118 W  [mpegts @ 00007f6445e10140] Packet corrupt
(stream = 1, dts = 2229304758).
2021-11-15 20:47:26.421122 W  [mpegts @ 00007f6445e10140] PES packet size
mismatch
2021-11-15 20:47:26.421126 W  [mpegts @ 00007f6445e10140] Packet corrupt
(stream = 2, dts = 2229307638).
2021-11-15 20:47:26.421294 I  AFD: codec AC3 has 2 channels
2021-11-15 20:47:26.421401 I  AFD: Opened codec 0x5640e3b1a380, id(AC3)
type(Audio)
2021-11-15 20:47:26.421457 I  AFD: Audio Track #1, of type (Normal) is A/V
stream #1 (id=0x64) and has 2 channels in the English language(6647399).
2021-11-15 20:47:26.421459 I  AFD: codec AC3 has 2 channels
2021-11-15 20:47:26.421558 I  AFD: Opened codec 0x5640e2ef5000, id(AC3)
type(Audio)
2021-11-15 20:47:26.421566 I  AFD: Audio Track #2, of type (Normal) is A/V
stream #2 (id=0x65) and has 2 channels in the Spanish; Castilian
language(7565409).
2021-11-15 20:47:26.422198 I  VideoOutput: SetDeinterlacing (Doublerate 0):
Single High|CPU|GLSL Double None
2021-11-15 20:47:26.422212 I  AFD: Using ffmpeg for video decoding
2021-11-15 20:47:26.422507 I  AFD: Opened codec 0x5640e398d240,
id(MPEG2VIDEO) type(Video)
2021-11-15 20:47:26.422516 I  AFD: Trying to select audio track (w/lang)
2021-11-15 20:47:26.423016 I  AFD: Selected track 0 (A/V Stream #1)
2021-11-15 20:47:26.423025 I  AFD: Audio data is planar
2021-11-15 20:47:26.423034 I  AFD: Initializing audio parms from audio
track #1
2021-11-15 20:47:26.423044 I  AFD: Audio format changed
from id(NONE)     -1Hz -1ch -1bps     (profile 0) to id( AC3)  48000Hz  2ch
32bps     (profile 0)
2021-11-15 20:47:26.423056 I  AOBase: Needs upmix from 2 -> 6 channels
2021-11-15 20:47:26.423059 I  AOBase: Killing AudioOutputDSP
2021-11-15 20:47:26.423065 I  AOBase: Original codec was AC3, 32 bit
floating point, 48 kHz, 2 channels
2021-11-15 20:47:26.423071 I  AOBase: enc(1), passthru(0), features
(AC3,DTS) configured_channels(6), 6 channels supported(1) max_channels(6)
2021-11-15 20:47:26.423072 I  AOBase: Reencoding decoded AC-3/DTS to AC-3
2021-11-15 20:47:26.423075 I  AOBase: Creating AC-3 Encoder with sr =
48000, ch = 6
2021-11-15 20:47:26.423099 I  DEnc: Init codecid=AC3, br=448000, sr=48000,
ch=6
2021-11-15 20:47:26.423153 E  [ac3_fixed @ 00007f644558dd60] Specified
sample format s16p is invalid or not supported
2021-11-15 20:47:26.423158 E  DEnc: Could not open codec, invalid bitrate
or samplerate
2021-11-15 20:47:26.423164 E  AudioOutput Error: Ac-3 Encoder
Initialization Failed
2021-11-15 20:47:26.423172 I  AOBase: Audio processing enabled
2021-11-15 20:47:26.423176 I  AOBase: Opening audio device
'hdmi:CARD=PCH,DEV=0' ch 6(2) sr 48000 sf signed 32 bit reenc 1
2021-11-15 20:47:26.423178 I  ALSA: OpenDevice hdmi:CARD=PCH,DEV=0
2021-11-15 20:47:26.476197 I  ALSA: SetParameters(format=10, channels=6,
rate=48000, buffer_time=500000, period_time=4)
2021-11-15 20:47:26.476378 I  ALSA: Buffer size range from 12 to 174762
2021-11-15 20:47:26.476387 I  ALSA: Period size range from 6 to 87381
2021-11-15 20:47:26.476435 I  ALSA: Buffer time = 500000 us
2021-11-15 20:47:26.476468 I  ALSA: Period time = 4 periods
2021-11-15 20:47:26.490564 I  ALSA: Buffer size = 24000 | Period size = 6000
2021-11-15 20:47:26.505045 E  ALSA: no playback control PCM found on mixer
device default
2021-11-15 20:47:26.505049 E  ALSA: Unable to open audio mixer. Volume
control disabled
2021-11-15 20:47:26.505053 I  AOBase: Audio fragment size: 72000
2021-11-15 20:47:26.505072 I  AOBase: Audio Stretch Factor: 1
2021-11-15 20:47:26.505120 I  AOBase: Ending Reconfigure()
2021-11-15 20:47:26.505129 N  AudioPlayer: Disabling Audio, reason is: Ac-3
Encoder Initialization Failed
2021-11-15 20:47:26.505195 I  AOBase: kickoffOutputAudioLoop: pid = 40129
2021-11-15 20:47:26.505250 I  AOBase: OutputAudioLoop: Play Event
2021-11-15 20:47:26.507179 I  Input #0, mpegts, from
'/srv/mythtv/livetv/15003_20211116014724.ts':
2021-11-15 20:47:26.507192 I  Duration: 00:00:01.50, start: 24769.092867,
bitrate: 1911 kb/s
2021-11-15 20:47:26.507282 I  Stream #0:0[0x61]: Video: mpeg2video (Main),
yuv420p(tv, top first), 704x480 [SAR 40:33 DAR 16:9], 1000 kb/s, 29.97 fps,
29.97 tbr, 90k tbn, 59.94 tbc
2021-11-15 20:47:26.507284 I  Side data:
2021-11-15 20:47:26.507292 I  cpb: bitrate max/min/avg: 3896000/0/0 buffer
size: 1835008 vbv_delay: N/A
2021-11-15 20:47:26.507330 I  Stream #0:1[0x64](eng): Audio: ac3 (AC-3 /
0x332D4341), 48000 Hz, stereo, fltp, 192 kb/s
2021-11-15 20:47:26.507356 I  Stream #0:2[0x65](spa): Audio: ac3 (AC-3 /
0x332D4341), 48000 Hz, stereo, fltp, 128 kb/s
2021-11-15 20:47:26.611862 N  Player(0): Waited 101ms for video buffers
AAAAAAAAAAAAAuLL
2021-11-15 20:47:26.715939 N  Player(0): Waited 205ms for video buffers
ULAAAAAAAAAAAULU
2021-11-15 20:47:26.820135 N  Player(0): Waited 310ms for video buffers
ULLAAAAAAAAAAUuU
2021-11-15 20:47:26.924378 N  Player(0): Waited 414ms for video buffers
ULUULAAAAAAAAUUU
2021-11-15 20:47:26.976695 I  VideoOutput: SetDeinterlacing (Doublerate 1):
Single High|CPU|GLSL Double High|CPU|GLSL
2021-11-15 20:47:26.977038 I  VideoOutput: SetDeinterlacing (Doublerate 1):
Single High|CPU|GLSL Double High|CPU|GLSL
2021-11-15 20:47:26.977134 I  GLVid: New frame format: None:None 704x480
(Tex: 2D) -> YUV420P:YUV420P 704x480 (Tex: 2D)
2021-11-15 20:47:27.161612 N  Player(0): Waited 101ms for video buffers
AUUUUULULAAAAAUA
2021-11-15 20:47:37.442561 I  TV::HandleStateChange(): Attempting to change
from WatchingLiveTV to None
2021-11-15 20:47:37.442691 I  ScreenSaverDBus: Screensaver uninhibited via
org.freedesktop.ScreenSaver
2021-11-15 20:47:37.442702 I  ScreenSaverX11: Uninhibited screensaver
2021-11-15 20:47:37.475858 I  AOBase: Killing AudioOutputDSP
2021-11-15 20:47:37.482981 I  AOBase: OutputAudioLoop: Stop Event
2021-11-15 20:47:37.482999 I  AOBase: kickoffOutputAudioLoop exiting
2021-11-15 20:47:37.530944 I  TV::HandleStateChange(): Changing from
WatchingLiveTV to None
2021-11-15 20:47:37.531591 I  TV::StartTV(): Exiting main playback loop.
2021-11-15 20:47:37.532082 N  Resuming idle timer
2021-11-15 20:47:38.950682 I  Created ExitPrompter
2021-11-15 20:47:39.895791 I  Deleted ExitPrompter
2021-11-15 20:47:39.916458 I  UICache: Pruning cache directory:
/home/jim/.mythtv/cache/remotecache
2021-11-15 20:47:39.916568 I  UICache: Kept 18 files, deleted 0 files, stat
error on 0 files
2021-11-15 20:47:39.916576 I  UICache: Pruning cache directory:
/home/jim/.mythtv/cache/thumbnails
2021-11-15 20:47:39.917249 I  UICache: Kept 177 files, deleted 0 files,
stat error on 0 files
2021-11-15 20:47:39.918478 I  Bonjour: De-registering service
'_mythfrontend._tcp.' on 'Mythfrontend on pop-os'
2021-11-15 20:47:39.919571 I  UDPListener: Disabling
2021-11-15 20:47:39.934056 I  InputHandler: Stopping
2021-11-15 20:47:39.934640 W  MythPainter: 60 images not yet de-allocated.
2021-11-15 20:47:39.934650 I  Clearing OpenGL painter cache.
2021-11-15 20:47:39.934887 I  OpenGL: MythRenderOpenGL closing
2021-11-15 20:47:39.937435 I  Display: Deleting
2021-11-15 20:47:39.939233 I  RAOP Device: Cleaning up.
2021-11-15 20:47:39.939238 I  AirPlay: Cleaning up.
2021-11-15 20:47:39.939418 I  Bonjour: De-registering service
'_airplay._tcp.' on 'MythTV on pop-os'
2021-11-15 20:47:39.939698 I  Shutting down UPnP client...
2021-11-15 20:47:42.603536 I  PowerDBus: Closing interfaces
2021-11-15 20:47:42.604485 I  Waiting for threads to exit.
2021-11-15 20:47:45.939929 I  Exiting
jim at pop-os:~/build/mythtv/mythtv$
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20211115/229117a2/attachment.htm>