[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