[mythtv-users] Audio dropouts .29

Jack McGee jack at greendesk.net
Sun Nov 25 22:16:32 UTC 2018


On 11/25/18 3:44 PM, Peter Bennett wrote:
> 
> 
> On 11/25/18 4:20 PM, Jack McGee wrote:
>> I blew out a working 16.04 and Mythtv .29 frontend.
>>
>>
>> Installed 18.04 and am having an issue playing back recordings. Audio 
>> dropouts of a few seconds every minute or so.
>>


> 
> Try -v playback and see if the "Waiting for video buffers" continue to 
> happen. If so the decoding is not fast enough. Check your playback 
> profile, make sure if using standard that you set number of CPUs to 4.

Just in case I did not do -v playback:

mythuser at mythfrontendlr:~$ mythfrontend -v playback
2018-11-25 15:01:57.552110 I  Setup Interrupt handler
2018-11-25 15:01:57.552127 I  Setup Terminated handler
2018-11-25 15:01:57.552132 I  Setup Segmentation fault handler
2018-11-25 15:01:57.552136 I  Setup Aborted handler
2018-11-25 15:01:57.552139 I  Setup Bus error handler
2018-11-25 15:01:57.552143 I  Setup Floating point exception handler
2018-11-25 15:01:57.552146 I  Setup Illegal instruction handler
2018-11-25 15:01:57.552152 I  Setup Real-time signal 0 handler
2018-11-25 15:01:57.552156 I  Setup User defined signal 1 handler
2018-11-25 15:01:57.552160 I  Setup User defined signal 2 handler
2018-11-25 15:01:57.552163 I  Setup Hangup handler
2018-11-25 15:01:57.552251 C  mythfrontend version:  [v29.1] www.mythtv.org
2018-11-25 15:01:57.552255 C  Qt version: compile: 5.9.5, runtime: 5.9.5
2018-11-25 15:01:57.552257 N  Enabled verbose msgs:  general playback
2018-11-25 15:01:57.552265 N  Setting Log Level to LOG_INFO
2018-11-25 15:01:57.563300 N  Using runtime prefix = /usr
2018-11-25 15:01:57.563302 I  Added logging to the console
2018-11-25 15:01:57.563304 N  Using configuration directory = 
/home/mythuser/.mythtv
2018-11-25 15:01:57.563337 I  Assumed character encoding: en_US.UTF-8
2018-11-25 15:01:57.563569 N  Empty LocalHostName.
2018-11-25 15:01:57.563572 I  Using localhost value of mythfrontendlr
2018-11-25 15:01:57.563594 I  Start up testing connections. DB 
192.168.1.105, BE , attempt 0, status dbAwake
2018-11-25 15:01:57.664281 I  Added syslogging
2018-11-25 15:01:59.095970 N  Setting QT default locale to en_US
2018-11-25 15:01:59.095996 I  Current locale en_US
2018-11-25 15:01:59.096092 N  Reading locale defaults from 
/usr/share/mythtv//locales/en_us.xml
2018-11-25 15:01:59.126460 I  ScreenSaverDBus: Created for DBus service: 
org.freedesktop.ScreenSaver
2018-11-25 15:01:59.132454 I  ScreenSaverDBus: Created for DBus service: 
org.gnome.SessionManager
2018-11-25 15:01:59.132949 I  Starting process manager
2018-11-25 15:01:59.133035 I  Starting process signal handler
2018-11-25 15:01:59.135138 I  Starting IO manager (write)
2018-11-25 15:01:59.135576 I  Starting IO manager (read)
2018-11-25 15:01:59.184890 I  ScreenSaverX11Private: DPMS is active.
2018-11-25 15:01:59.229061 N  Desktop video mode: 3840x2160 30.000 Hz
2018-11-25 15:01:59.316947 I  max_width: 3840 max_height: 2160
2018-11-25 15:01:59.365342 I  Listening on TCP 0.0.0.0:6547
2018-11-25 15:01:59.368010 I  Listening on TCP [::]:6547
2018-11-25 15:02:01.539652 I  Loading en_us translation for module 
mythfrontend
2018-11-25 15:02:01.559463 E  LIRC: Failed to connect to Unix socket 
'/var/run/lirc/lircd'
			eno: No such file or directory (2)
2018-11-25 15:02:01.559534 I  No joystick configuration found, not 
enabling joystick control
2018-11-25 15:02:01.672099 E  CECAdapter: Failed to find any CEC devices.
2018-11-25 15:02:01.672199 I  CECAdapter: Closing down CEC.
2018-11-25 15:02:01.672216 I  UDPListener: Enabling
2018-11-25 15:02:01.673531 I  Binding to UDP 0.0.0.0:6948
2018-11-25 15:02:01.673556 I  Binding to UDP [::]:6948
2018-11-25 15:02:01.777393 I  Using Frameless Window
2018-11-25 15:02:01.777402 I  Using Full Screen Window
2018-11-25 15:02:01.838615 I  UI Screen Resolution: 3840 x 2160
2018-11-25 15:02:01.884823 I  OpenGL: Sync to VBlank is enabled (good!)
2018-11-25 15:02:01.951330 I  Trying the OpenGL 2.0 render
2018-11-25 15:02:01.955979 I  OpenGL painter using existing OpenGL context.
2018-11-25 15:02:01.975524 I  OpenGL2: GLSL supported
2018-11-25 15:02:01.975569 I  OpenGL: OpenGL vendor  : NVIDIA Corporation
2018-11-25 15:02:01.975571 I  OpenGL: OpenGL renderer: GeForce GT 
1030/PCIe/SSE2
2018-11-25 15:02:01.975572 I  OpenGL: OpenGL version : 4.6.0 NVIDIA 390.77
2018-11-25 15:02:01.975576 I  OpenGL: Max texture size: 32768 x 32768
2018-11-25 15:02:01.975577 I  OpenGL: Max texture units: 4
2018-11-25 15:02:01.975580 I  OpenGL: Direct rendering: Yes
2018-11-25 15:02:01.975583 I  OpenGL: Extensions Supported: 1e3b
2018-11-25 15:02:01.975584 I  OpenGL: PixelBufferObject support available
2018-11-25 15:02:01.976278 I  OpenGL: Initialised MythRenderOpenGL
libpng warning: iCCP: known incorrect sRGB profile
libpng warning: iCCP: known incorrect sRGB profile
2018-11-25 15:02:02.173997 I  MythUIWebBrowser: Loading css from - 
file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css
2018-11-25 15:02:02.177977 I  MythUIWebBrowser: enabling plugins
2018-11-25 15:02:02.251807 I  MythCoreContext::ConnectCommandSocket(): 
Connecting to backend server: 192.168.1.105:6543 (try 1 of 1)
2018-11-25 15:02:02.257029 E  RAOP Conn: Failed to read key from: 
/home/mythuser/.mythtv/RAOPKey.rsa
2018-11-25 15:02:02.257036 E  RAOP Device: Aborting startup - no key found.
2018-11-25 15:02:02.258441 I  AirPlay: Created airplay objects.
2018-11-25 15:02:02.259264 I  MythCoreContext::CheckProtoVersion(): 
Using protocol version 91 BuzzOff
2018-11-25 15:02:02.266608 I  Current MythTV Schema Version 
(DBSchemaVer): 1348
2018-11-25 15:02:02.270648 I  FilterManager: GetFilterInfo(greedyhdeint) 
returning: 0x563248ef5d30
2018-11-25 15:02:02.270654 I  FilterManager: 
GetFilterInfo(greedyhdoubleprocessdeint) returning: 0x5632487e54a0
2018-11-25 15:02:02.271349 I  VDP: decoder<->render support: ffmpeg 
null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi 
openglvaapi
2018-11-25 15:02:02.271355 I  VDP: decoder<->render support: vdpau 
vdpau nullvdpau
2018-11-25 15:02:02.271358 I  VDP: decoder<->render support: vaapi 
nullvaapi openglvaapi
2018-11-25 15:02:02.271363 I  VDP: decoder<->render support: crystalhd 
null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi
2018-11-25 15:02:02.275264 I  Listening on TCP 0.0.0.0:5100
2018-11-25 15:02:02.277560 I  Listening on TCP [::]:5100
2018-11-25 15:02:02.939672 N  Registering Internal as a media playback 
plugin.
2018-11-25 15:02:02.942722 A  MMUnix:CheckMountable: DBus interface 
error: The name org.freedesktop.UDisks was not provided by any .service 
files
2018-11-25 15:02:02.943479 W  MMUnix:UDisks2 service found. Media 
Monitor does not support this yet!
2018-11-25 15:02:02.950386 I  Current MythMusic Schema Version 
(MusicDBSchemaVer): 1024
2018-11-25 15:02:03.000860 I  Loading en_us translation for module mythmusic
2018-11-25 15:02:03.008070 E  Error Loading en_us translation for module 
mythnews
2018-11-25 15:02:03.019456 E  Error Loading en_us translation for module 
mythweather
2018-11-25 15:02:03.036140 N  Found mainmenu.xml for theme 
'MythCenterXMAS-wide'
2018-11-25 15:02:03.053759 I  Checking for theme updates every hour
2018-11-25 15:02:03.053908 I  Registering HouseKeeperTask 
'HardwareProfiler'.
2018-11-25 15:02:03.060542 I  Queueing HouseKeeperTask 'HardwareProfiler'.
2018-11-25 15:02:03.060548 I  Starting HouseKeeper.
2018-11-25 15:02:03.239456 I  Bonjour: Service registration complete: 
name 'MythTV on mythfrontendlr' type '_airplay._tcp.' domain: 'local.'
2018-11-25 15:02:03.394205 I  Bonjour: Service registration complete: 
name 'Mythfrontend on mythfrontendlr' type '_mythfrontend._tcp.' domain: 
'local.'
2018-11-25 15:02:11.167761 I  TV::TV(): Creating TV object
2018-11-25 15:02:11.182342 I  TV::TV(): Finished creating TV object
2018-11-25 15:02:11.182359 N  Suspending idle timer
2018-11-25 15:02:11.184705 I  TV::Init(): Created TvPlayWindow.
2018-11-25 15:02:11.252190 I  TV::HandleStateChange(): Attempting to 
change from None to WatchingPreRecorded
2018-11-25 15:02:11.255913 I 
FileRingBuf(/mnt/media1/myth/recordings/2051_20181125143000.ts): 
OpenFile(/mnt/media1/myth/recordings/2051_20181125143000.ts, 2000 ms)
2018-11-25 15:02:11.324364 I  TV::StartPlayer(): Elapsed time since TV 
constructor was called: 157 ms
2018-11-25 15:02:11.443091 N  AudioPlayer: Enabling Audio
2018-11-25 15:02:11.508358 I  Player(0): Stretch Factor 1, allow passthru
2018-11-25 15:02:11.514118 I  AFD: Buffer size: 32768 streamed 0 seekable 1
2018-11-25 15:02:12.670307 I  AFD: Stream #0, has id 0x31 codec id 
MPEG2VIDEO, type Video, bitrate 0 at 0x56324b662460
2018-11-25 15:02:12.670330 I  AFD: Stream #1, has id 0x34 codec id AC3, 
type Audio, bitrate 384000 at 0x563249f00ac0
2018-11-25 15:02:12.670340 I  AFD: codec AC3 has 6 channels
2018-11-25 15:02:12.670349 I  AFD: Looking for decoder for AC3
2018-11-25 15:02:12.670797 I  AFD: Opened codec 0x563249f00f00, id(AC3) 
type(Audio)
2018-11-25 15:02:12.671300 I  AFD: Stream #2, has id 0x35 codec id AC3, 
type Audio, bitrate 192000 at 0x56324a455560
2018-11-25 15:02:12.671310 I  AFD: codec AC3 has 2 channels
2018-11-25 15:02:12.671317 I  AFD: Looking for decoder for AC3
2018-11-25 15:02:12.671724 I  AFD: Opened codec 0x56324a23dce0, id(AC3) 
type(Audio)
2018-11-25 15:02:12.671792 I  AFD: Trying to select best video track
2018-11-25 15:02:12.671824 I  AFD: Selected track #0 (id 0x31 codec id 
MPEG2VIDEO, type Video, bitrate 500000 at 0x56324b662460)
2018-11-25 15:02:12.678560 I  VDP: Accepting: cmp(>= 0 720) w() h() 
framerate() codecs() dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) 
osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) 
filt(vdpaucolorspace=auto)
2018-11-25 15:02:12.678633 I  VDP: Accepting: cmp(> 0 0) w() h() 
framerate() codecs() dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) 
osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) 
filt(vdpaucolorspace=auto)
2018-11-25 15:02:12.678663 I  VDP: LoadBestPreferences(1920x1080, 
29.970, mpeg2video)
2018-11-25 15:02:12.678727 I  VDP: LoadBestPreferences Result cmp0:>= 0 
720, cmp1:, prio:1, w:, h:, fps:, codecs:, decoder:vdpau, 
renderer:vdpau, deint:vdpaubasicdoublerate
2018-11-25 15:02:12.678788 I  VDPAU: Checking VDPAU support.
2018-11-25 15:02:12.711943 I  VDPAU: Version 1
2018-11-25 15:02:12.711957 I  VDPAU: Information NVIDIA VDPAU Driver 
Shared Library  390.77  Tue Jul 10 22:09:49 PDT 2018
2018-11-25 15:02:12.711981 I  VDPAU: HQ scaling level 1 of 9 available.
2018-11-25 15:02:12.711987 I  VDPAU: MPEG4 hardware acceleration supported.
2018-11-25 15:02:12.711990 I  VDPAU: HEVC hardware acceleration supported.
2018-11-25 15:02:12.717974 I  AFD: Using 1 CPUs for decoding
2018-11-25 15:02:12.717988 I  AFD: InitVideoCodec() 0x56324b6628a0 
id(MPEG2VIDEO) type (Video).
2018-11-25 15:02:12.718024 I  AFD: Selected FPS is 29.97 (avg 29.97 
codec 29.97 container 90000 estimated 29.97)
2018-11-25 15:02:12.718049 I  Player(0): SetFrameInterval ps:1 scan:3
2018-11-25 15:02:12.718063 I  Player(0): detectInterlace(Detect Scan, 
Interlaced Scan, 29.97, 1080) ->Interlaced Scan
2018-11-25 15:02:12.718175 I  AFD: EIA-708 caption service #1 is in the 
English language.
2018-11-25 15:02:12.718184 I  AFD: EIA-608 caption service #1 is in the 
English language.
2018-11-25 15:02:12.718200 I  AFD: Using vdpau for video decoding
2018-11-25 15:02:12.718219 I  AFD: Opened codec 0x56324b6628a0, 
id(MPEG2VIDEO) type(Video)
2018-11-25 15:02:12.720983 I  AOBase: Opening audio device 
'hdmi:CARD=NVidia,DEV=0' ch 2(6) sr 48000 sf signed 32 bit reenc 0
2018-11-25 15:02:12.723991 W  ALSA: Requested 500000us got 170666 buffer 
time
2018-11-25 15:02:12.724076 W  ALSA: Try to manually increase audio 
buffer with: echo 192 | sudo tee /proc/asound/card1/pcm3p/sub0/prealloc
2018-11-25 15:02:13.084556 E  ALSA: no playback control PCM found on 
mixer device default
2018-11-25 15:02:13.084573 E  ALSA: Unable to open audio mixer. Volume 
control disabled
2018-11-25 15:02:13.084675 I  Dec: Selected track #1 (type 2) in the 
Unknown language(0)
2018-11-25 15:02:13.084692 I  Dec: Selected track #1 (type 4) in the 
English language(6647399)
2018-11-25 15:02:13.084704 I  Dec: Selected track #1 (type 5) in the 
English language(6647399)
2018-11-25 15:02:13.084719 I  Dec: Resyncing position map. posmapStarted 
= 0 livetv(0) watchingRec(0)
2018-11-25 15:02:13.153212 I  Dec: Position map filled from DB to: 54797
2018-11-25 15:02:13.156200 I  Dec: Duration map filled from DB to: 54797
2018-11-25 15:02:13.156480 I  Dec: SyncPositionMap prerecorded, from DB: 
1291 entries
2018-11-25 15:02:13.156498 I  Dec: SyncPositionMap, new totframes: 
54797, new length: 1828, posMap size: 1291
2018-11-25 15:02:13.180703 I  AFD: Position map found
2018-11-25 15:02:13.180722 I  AFD: Successfully opened decoder for file: 
"/mnt/media1/myth/recordings/2051_20181125143000.ts". novideo(0)
2018-11-25 15:02:13.273733 I  VideoOutput: Allowed renderers: vdpau
2018-11-25 15:02:13.273772 I  VideoOutput: Allowed renderers (filt: 
vdpau): vdpau
2018-11-25 15:02:13.338123 I  VDP: Accepting: cmp(>= 0 720) w() h() 
framerate() codecs() dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) 
osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpaubasic) 
filt(vdpaucolorspace=auto)
2018-11-25 15:02:13.338199 I  VDP: Accepting: cmp(> 0 0) w() h() 
framerate() codecs() dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) 
osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) 
filt(vdpaucolorspace=auto)
2018-11-25 15:02:13.338225 I  VDP: LoadBestPreferences(1920x1080, 
29.970, mpeg2video)
2018-11-25 15:02:13.338300 I  VDP: LoadBestPreferences Result cmp0:>= 0 
720, cmp1:, prio:1, w:, h:, fps:, codecs:, decoder:vdpau, 
renderer:vdpau, deint:vdpaubasicdoublerate
2018-11-25 15:02:13.338352 I  VideoOutput: Preferred renderer: vdpau
2018-11-25 15:02:13.338359 I  VideoOutput: Trying video renderer: 'vdpau'
2018-11-25 15:02:13.577133 I  VideoOutWindow::SetPIPState. pip_state: 0]
2018-11-25 15:02:13.577158 I  Clearing OpenGL painter cache.
2018-11-25 15:02:13.697015 I  Display Rect  left: 0, top: 270, width: 
3840, height: 1620, aspect: 1.33333
2018-11-25 15:02:13.697021 I  Video Rect    left: 0, top: 0, width: 
1920, height: 1080, aspect: 1.77778
2018-11-25 15:02:13.697027 I  VDP: LoadBestPreferences(1920x1088, 
29.970, mpeg2video)
2018-11-25 15:02:13.697051 I  VDP: LoadBestPreferences Result cmp0:>= 0 
720, cmp1:, prio:1, w:, h:, fps:, codecs:, decoder:vdpau, 
renderer:vdpau, deint:vdpaubasicdoublerate
2018-11-25 15:02:13.697056 I  Display Rect  left: 0, top: 270, width: 
3840, height: 1620, aspect: 1.33333
2018-11-25 15:02:13.697059 I  Video Rect    left: 0, top: 0, width: 
1920, height: 1080, aspect: 1.77778
2018-11-25 15:02:13.697063 I  VDP: SetVideoRenderer(vdpau)
2018-11-25 15:02:13.697066 I  VDP: SetVideoRender(vdpau) == 
GetVideoRenderer()
2018-11-25 15:02:13.697257 I  VideoOutput: Pixel dimensions: Screen 
3840x2160, window 3840x2160
2018-11-25 15:02:13.697262 I  VideoOutput: Actual display dimensions: 
1300x731 mm  Aspect: 1.77839
2018-11-25 15:02:13.697264 I  VideoOutput: Estimated window dimensions: 
1300x731 mm  Aspect: 1.77839
2018-11-25 15:02:13.744012 I  VDPAU: Created 2 output surfaces.
2018-11-25 15:02:13.744020 I  VDPAU: Set colorkey to 0x20202
2018-11-25 15:02:13.744023 I  VDPAU: Created VDPAU render device 3840x2160
2018-11-25 15:02:13.749444 I  VidOutVDPAU: Created VDPAU osd (3840x2160)
2018-11-25 15:02:13.771778 I  ColourSpace: PictureAttributes: 
Brightness, Contrast, Colour, Hue, Studio Levels,
2018-11-25 15:02:13.771785 I  VidOutVDPAU: Using ITU BT.709 colorspace
2018-11-25 15:02:13.771801 I  Display Rect  left: 0, top: 0, width: 
3840, height: 2160, aspect: 1.77778
2018-11-25 15:02:13.771804 I  Video Rect    left: 0, top: 0, width: 
1920, height: 1080, aspect: 1.77778
2018-11-25 15:02:13.771806 I  VidOutVDPAU: Created VDPAU context (GPU 
decode)
2018-11-25 15:02:13.774451 I  Over/underscan. V: 0, H: 0
2018-11-25 15:02:13.774458 I  Display Rect  left: 0, top: 0, width: 
3840, height: 2160, aspect: 1.77778
2018-11-25 15:02:13.774461 I  Video Rect    left: 0, top: 0, width: 
1920, height: 1080, aspect: 1.77778
2018-11-25 15:02:13.774466 N  Player(0): Forcing decode extra audio 
option on (Video method requires it).
2018-11-25 15:02:13.774478 I  FilterManager: GetFilterInfo(convert) 
returning: 0x0
2018-11-25 15:02:13.774487 I  Player(0): 
LoadFilters('vdpaucolorspace=auto'..) -> 0x0
2018-11-25 15:02:13.777369 I  Player(0): Decoder thread starting.
2018-11-25 15:02:13.777375 I  AFD: DoFastForward(371 (0), do discard frames)
2018-11-25 15:02:13.777378 I  Dec: DoFastForward(371 (0), do discard frames)
2018-11-25 15:02:13.777386 I  Dec: FindPosition(371, search not 
adjusted) -->
			[9:348(22011228),10:399(25296716)]
2018-11-25 15:02:13.777844 I  AFD: SeekReset(348, 23, do flush, do discard)
2018-11-25 15:02:13.777853 I  AFD: SeekReset() flushing
2018-11-25 15:02:13.777867 I  VidOutVDPAU: DiscardFrames(1)
2018-11-25 15:02:13.777892 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA
2018-11-25 15:02:13.777909 I  VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAA -- done
2018-11-25 15:02:13.777920 I  VidOutVDPAU: DiscardFrames() 3: 
AAAAAAAAAAAAAA -- done()
2018-11-25 15:02:13.931154 I  VidOutVDPAU: Created VDPAU decoder (2 ref 
frames)




More information about the mythtv-users mailing list