Page 1 of 1

mkv containers not playing...

Posted: Sat Oct 05, 2019 9:33 am
by diyhouse
I have created mkv files with various audio streams,.. but all with converted down audio streams,.. AC3 to 5.1 and Stereo.

When I try to play one of these files,.. the mythtv frontend ( on a combined backend/ frontend,. running 18.04 Linux with 29.1... plus all updates... ) just dies,... However,.. trying the same with vlc,.. the files play without issue.

see log of frontend log as follows at end:

Now as far as I can tell, IMHO,. the issue is to do with mythtv not being able to handle to AC3 audio stream,.. ( which I know is not true ),...

I have found the frontend audio setups with the audio setup bits in,.. and although AC3 and E-AC3 exists ( and others ) in the list.. mythtv will not let me scroll down to that option and enable that codec,... is there another step I need to invoke in order to successfully play my mkv's.
do I need to load the codec?

Is anyone able to offer some help suggestions...

Many tx

Code: Select all

2019-10-03 18:02:21.479792 I  Setup Interrupt handler
2019-10-03 18:02:21.479808 I  Setup Terminated handler
2019-10-03 18:02:21.479812 I  Setup Segmentation fault handler
2019-10-03 18:02:21.479817 I  Setup Aborted handler
2019-10-03 18:02:21.479821 I  Setup Bus error handler
2019-10-03 18:02:21.479825 I  Setup Floating point exception handler
2019-10-03 18:02:21.479829 I  Setup Illegal instruction handler
2019-10-03 18:02:21.479834 I  Setup Real-time signal 0 handler
2019-10-03 18:02:21.479838 I  Setup User defined signal 1 handler
2019-10-03 18:02:21.479842 I  Setup User defined signal 2 handler
2019-10-03 18:02:21.479846 I  Setup Hangup handler
2019-10-03 18:02:21.479907 C  mythfrontend version: fixes/29 [v29.1-37-g183ac5d583] www.mythtv.org
2019-10-03 18:02:21.479911 C  Qt version: compile: 5.9.5, runtime: 5.9.5
2019-10-03 18:02:21.479912 N  Enabled verbose msgs:  general
2019-10-03 18:02:21.479920 N  Setting Log Level to LOG_INFO
2019-10-03 18:02:21.490324 N  Using runtime prefix = /usr/local
2019-10-03 18:02:21.490328 N  Using configuration directory = /home/mark/.mythtv
2019-10-03 18:02:21.490383 I  Assumed character encoding: en_GB.UTF-8
2019-10-03 18:02:21.490456 I  Added logging to the console
2019-10-03 18:02:21.490718 I  Using localhost value of mythtv0
2019-10-03 18:02:21.490751 I  Start up testing connections. DB mythtv0, BE , attempt 0, status dbAwake
2019-10-03 18:02:22.507869 N  Setting QT default locale to en_US
2019-10-03 18:02:22.507884 I  Current locale en_US
2019-10-03 18:02:22.507945 N  Reading locale defaults from /usr/local/share/mythtv//locales/en_us.xml
2019-10-03 18:02:22.518454 I  ScreenSaverDBus: Created for DBus service: org.freedesktop.ScreenSaver
2019-10-03 18:02:22.519878 I  Starting process manager
2019-10-03 18:02:22.519872 I  Starting process signal handler
2019-10-03 18:02:22.519895 I  Starting IO manager (read)
2019-10-03 18:02:22.521218 I  Starting IO manager (write)
2019-10-03 18:02:22.571261 I  ScreenSaverX11Private: DPMS is disabled.
2019-10-03 18:02:22.596127 N  Desktop video mode: 1920x1080 60.000 Hz
2019-10-03 18:02:22.955269 I  Listening on TCP 0.0.0.0:6547
2019-10-03 18:02:22.955653 I  Listening on TCP [::]:6547
2019-10-03 18:02:27.097952 I  Loading en_gb translation for module mythfrontend
2019-10-03 18:02:27.107871 E  LIRC: Failed to connect to Unix socket '/dev/lircd'
			eno: No such file or directory (2)
2019-10-03 18:02:27.107896 I  No joystick configuration found, not enabling joystick control
2019-10-03 18:02:27.215766 E  CECAdapter: Failed to find any CEC devices.
2019-10-03 18:02:27.215857 I  CECAdapter: Closing down CEC.
2019-10-03 18:02:27.215878 I  UDPListener: Enabling
2019-10-03 18:02:27.216555 I  Binding to UDP 0.0.0.0:6948
2019-10-03 18:02:27.216590 I  Binding to UDP [::]:6948
2019-10-03 18:02:27.245376 I  Using Frameless Window
2019-10-03 18:02:27.245982 I  UI Screen Resolution: 1844 x 1033
2019-10-03 18:02:27.308871 W  OpenGL: Could not determine whether Sync to VBlank is enabled.
2019-10-03 18:02:27.318692 I  Trying the OpenGL 2.0 render
2019-10-03 18:02:27.319903 I  OpenGL painter using existing OpenGL context.
2019-10-03 18:02:27.329781 I  OpenGL2: GLSL supported
2019-10-03 18:02:27.329822 I  OpenGL: OpenGL vendor  : Intel Open Source Technology Center
2019-10-03 18:02:27.329827 I  OpenGL: OpenGL renderer: Mesa DRI Intel(R) Ivybridge Desktop 
2019-10-03 18:02:27.329830 I  OpenGL: OpenGL version : 3.0 Mesa 19.0.8
2019-10-03 18:02:27.329835 I  OpenGL: Max texture size: 16384 x 16384
2019-10-03 18:02:27.329838 I  OpenGL: Max texture units: 8
2019-10-03 18:02:27.329851 I  OpenGL: Direct rendering: Yes
2019-10-03 18:02:27.329856 I  OpenGL: Extensions Supported: 1e1b
2019-10-03 18:02:27.329858 I  OpenGL: PixelBufferObject support available
2019-10-03 18:02:27.330562 I  OpenGL: Initialised MythRenderOpenGL
2019-10-03 18:02:27.574942 I  MythUIWebBrowser: Loading css from - file:///usr/local/share/mythtv/themes/default/htmls/mythbrowser.css
2019-10-03 18:02:27.577508 I  MythUIWebBrowser: enabling plugins
2019-10-03 18:02:27.591295 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.3.47:6543 (try 1 of 1)
2019-10-03 18:02:27.592148 I  MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2019-10-03 18:02:27.622393 I  Current MythTV Schema Version (DBSchemaVer): 1348
2019-10-03 18:02:27.970510 N  Registering Internal as a media playback plugin.
2019-10-03 18:02:27.971755 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2019-10-03 18:02:27.973069 W  MMUnix:UDisks2 service found. Media Monitor does not support this yet!
2019-10-03 18:02:27.984901 I  Loading en_gb translation for module mytharchive
2019-10-03 18:02:27.988082 N  Registering WebBrowser as a media playback plugin.
2019-10-03 18:02:27.988129 I  Loading en_gb translation for module mythbrowser
2019-10-03 18:02:27.991451 I  Loading en_gb translation for module mythgame
2019-10-03 18:02:27.994121 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1024
2019-10-03 18:02:28.006862 I  Loading en_gb translation for module mythmusic
2019-10-03 18:02:28.008754 I  Loading en_gb translation for module mythnetvision
2019-10-03 18:02:28.011879 I  Loading en_gb translation for module mythnews
2019-10-03 18:02:28.014888 I  Loading en_gb translation for module mythweather
2019-10-03 18:02:28.017210 I  ZMClient: no valid IP or port found for mythzmserver
2019-10-03 18:02:28.018204 I  Loading en_gb translation for module mythzoneminder
2019-10-03 18:02:28.019886 I  Listening on TCP 0.0.0.0:6546
2019-10-03 18:02:28.020393 I  Listening on TCP [::]:6546
2019-10-03 18:02:28.048585 N  Found mainmenu.xml for theme 'MythCenter-wide'
2019-10-03 18:02:28.051035 I  Checking for theme updates every hour
2019-10-03 18:02:28.051168 I  Registering HouseKeeperTask 'HardwareProfiler'.
2019-10-03 18:02:28.051934 I  Starting HouseKeeper.
2019-10-03 18:02:28.116154 I  Bonjour: Service registration complete: name 'Mythfrontend on mythtv0' type '_mythfrontend._tcp.' domain: 'local.'
2019-10-03 18:02:45.710623 N  Suspending idle timer
2019-10-03 18:02:45.717136 I  TV::TV(): Creating TV object
2019-10-03 18:02:45.749945 N  Suspending idle timer
2019-10-03 18:02:45.753374 I  TV::Init(): Created TvPlayWindow.
2019-10-03 18:02:45.758483 I  TV::HandleStateChange(): Attempting to change from None to WatchingVideo
2019-10-03 18:02:45.841551 N  AudioPlayer: Enabling Audio
2019-10-03 18:02:46.116332 I  AFD: codec AAC has 2 channels
2019-10-03 18:02:46.116768 I  AFD: Opened codec 0x555735173960, id(AAC) type(Audio)
2019-10-03 18:02:46.116837 I  AFD: codec AAC has 2 channels
2019-10-03 18:02:46.117246 I  AFD: Opened codec 0x55573518d040, id(AAC) type(Audio)
2019-10-03 18:02:46.121338 I  AFD: Opened codec 0x55573516d640, id(H265) type(Video)
2019-10-03 18:02:46.122674 I  AOBase: Opening audio device 'hdmi:CARD=PCH,DEV=0' ch 2(2) sr 48000 sf signed 32 bit reenc 0
2019-10-03 18:02:46.124270 W  ALSA: Requested 500000us got 170666 buffer time
2019-10-03 18:02:46.124392 W  ALSA: Try to manually increase audio buffer with: echo 192 | sudo tee /proc/asound/card0/pcm3p/sub0/prealloc
2019-10-03 18:02:46.132720 E  ALSA: no playback control PCM found on mixer device default
2019-10-03 18:02:46.132728 E  ALSA: Unable to open audio mixer. Volume control disabled
2019-10-03 18:02:46.145040 I  Setup Interrupt handler
2019-10-03 18:02:46.145051 I  Setup Terminated handler
2019-10-03 18:02:46.145098 I  VideoOutputXv: XVideo Adaptor Name: 'GLAMOR Textured Video'
2019-10-03 18:02:46.145106 I  VideoOutput: CalcHueBase(GLAMOR Textured Video): Unknown adaptor, hue may be wrong.
2019-10-03 18:02:46.145109 I  VideoOutput: Please open a ticket if you need to adjust the hue.
2019-10-03 18:02:46.154483 I  OSD: Base theme size: 1280x720
2019-10-03 18:02:46.154498 I  OSD: Scaling factors: 0.5625x0.594444
2019-10-03 18:02:46.182568 I  OSD: Base theme size: 1280x720
2019-10-03 18:02:46.182577 I  OSD: Scaling factors: 0.5625x0.594444
2019-10-03 18:02:46.202950 I  Player(0): Video timing method: DRM
2019-10-03 18:02:46.218670 I  TV::StartPlayer(): Created player.
2019-10-03 18:02:46.218692 I  TV::HandleStateChange(): Changing from None to WatchingVideo
2019-10-03 18:02:46.220825 I  TV::HandleStateChange(): Main UI disabled.
2019-10-03 18:02:46.220883 I  TV::StartTV(): Entering main playback loop.
2019-10-03 18:02:46.222133 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.ScreenSaver. cookie 4. nom nom
2019-10-03 18:02:46.251517 C  Received Segmentation fault: Code 128, PID 0, UID 0, Value 0x440044004000400
2019-10-03 18:02:46.351671 I  TV::StartTV(): Exiting main playback loop.
2019-10-03 18:02:46.463656 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:46.563768 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:46.663870 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:46.763974 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:46.864071 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:46.964168 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:47.064264 W  Player(0): Waited 100ms for decoder to pause
2019-10-03 18:02:47.164361 W  Player(0): Waited 100ms for decoder to pause

Re: mkv containers not playing...

Posted: Sat Oct 05, 2019 7:05 pm
by blm-ubunet
AC3 (A52) can be 5.1 or stereo & using several bit rates.
Myth can decode or pass-thru to HT-amp over HDMI/IEC952(/DP/DVI in some.)
Did you mean converted to AAC?

Did you create the mkv in "makemkv"?

The FE audio setup stuff is not about decoding support but controlling the audio output formats that are supported by connected hardware.
Although some of that does influence internal decoding choices.

I thought XVideo was dead & buried.. Have you tried VAAPI or OpenGL decoding.
More logging needed. maybe try run FE from terminal with options "-v audio" & redirect output to a file.

Re: mkv containers not playing...

Posted: Sat Oct 05, 2019 8:20 pm
by diyhouse
Hi Tx for responding,.. I am using 'Handbrake' as my conversion tool...
and it is taking the RAW english AC3 5.1ch, 48Khz @ 384kbps -> AAC avcodec, Dolby Pro LogicII, 48Khz @ 160kbps with DRC off

I found these two 'E' lines in the log file

Code: Select all

2019-10-05 21:05:46.554314 E  ALSA: no playback control PCM found on mixer device default
2019-10-05 21:05:46.554319 E  ALSA: Unable to open audio mixer. Volume control disabled
but this is later followed by a line:-

Code: Select all

2019-10-05 21:05:46.682970 C  Received Segmentation fault: Code 128, PID 0, UID 0, Value 0x440044004000400
which looks pretty fatal....
But I do not know enough, as to,. 'is this the cause of my failure,.. or am I going in the wrong direction?
Your comments are welcome,...

rgds


and this is the more detailed complete error log

Code: Select all

2019-10-05 21:05:24.597455 I  No joystick configuration found, not enabling joystick control
2019-10-05 21:05:24.706976 E  CECAdapter: Failed to find any CEC devices.
2019-10-05 21:05:24.707133 I  CECAdapter: Closing down CEC.
2019-10-05 21:05:24.707161 I  UDPListener: Enabling
2019-10-05 21:05:24.707911 I  Binding to UDP 0.0.0.0:6948
2019-10-05 21:05:24.707945 I  Binding to UDP [::]:6948
2019-10-05 21:05:24.739422 I  Using Frameless Window
2019-10-05 21:05:24.740065 I  UI Screen Resolution: 1844 x 1033
2019-10-05 21:05:24.811353 W  OpenGL: Could not determine whether Sync to VBlank is enabled.
2019-10-05 21:05:24.826082 I  Trying the OpenGL 2.0 render
2019-10-05 21:05:24.828226 I  OpenGL painter using existing OpenGL context.
2019-10-05 21:05:24.839574 I  OpenGL2: GLSL supported
2019-10-05 21:05:24.839622 I  OpenGL: OpenGL vendor  : Intel Open Source Technology Center
2019-10-05 21:05:24.839627 I  OpenGL: OpenGL renderer: Mesa DRI Intel(R) Ivybridge Desktop 
2019-10-05 21:05:24.839631 I  OpenGL: OpenGL version : 3.0 Mesa 19.0.8
2019-10-05 21:05:24.839637 I  OpenGL: Max texture size: 16384 x 16384
2019-10-05 21:05:24.839640 I  OpenGL: Max texture units: 8
2019-10-05 21:05:24.839655 I  OpenGL: Direct rendering: Yes
2019-10-05 21:05:24.839661 I  OpenGL: Extensions Supported: 1e1b
2019-10-05 21:05:24.839664 I  OpenGL: PixelBufferObject support available
2019-10-05 21:05:24.840456 I  OpenGL: Initialised MythRenderOpenGL
2019-10-05 21:05:25.075731 I  MythUIWebBrowser: Loading css from - file:///usr/local/share/mythtv/themes/default/htmls/mythbrowser.css
2019-10-05 21:05:25.077463 I  MythUIWebBrowser: enabling plugins
2019-10-05 21:05:25.089611 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.3.47:6543 (try 1 of 1)
2019-10-05 21:05:25.090317 I  MythCoreContext::CheckProtoVersion(): Using protocol version 91 BuzzOff
2019-10-05 21:05:25.123774 I  Current MythTV Schema Version (DBSchemaVer): 1348
2019-10-05 21:05:25.500222 N  Registering Internal as a media playback plugin.
2019-10-05 21:05:25.501565 A  MMUnix:CheckMountable: DBus interface error: The name org.freedesktop.UDisks was not provided by any .service files
2019-10-05 21:05:25.502354 W  MMUnix:UDisks2 service found. Media Monitor does not support this yet!
2019-10-05 21:05:25.516530 I  Loading en_gb translation for module mytharchive
2019-10-05 21:05:25.521033 N  Registering WebBrowser as a media playback plugin.
2019-10-05 21:05:25.521108 I  Loading en_gb translation for module mythbrowser
2019-10-05 21:05:25.526017 I  Loading en_gb translation for module mythgame
2019-10-05 21:05:25.530012 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1024
2019-10-05 21:05:25.542878 I  Loading en_gb translation for module mythmusic
2019-10-05 21:05:25.545814 I  Loading en_gb translation for module mythnetvision
2019-10-05 21:05:25.548983 I  Loading en_gb translation for module mythnews
2019-10-05 21:05:25.554183 E  SourceManager: NeedSourceFor: Unable to find source for 4, United_Kingdom/England/London, 0
2019-10-05 21:05:25.554267 I  Loading en_gb translation for module mythweather
2019-10-05 21:05:25.557158 I  ZMClient: no valid IP or port found for mythzmserver
2019-10-05 21:05:25.558309 I  Loading en_gb translation for module mythzoneminder
2019-10-05 21:05:25.559922 I  Listening on TCP 0.0.0.0:6546
2019-10-05 21:05:25.560490 I  Listening on TCP [::]:6546
2019-10-05 21:05:25.590992 N  Found mainmenu.xml for theme 'MythCenter-wide'
2019-10-05 21:05:25.593571 I  Checking for theme updates every hour
2019-10-05 21:05:25.597538 I  Registering HouseKeeperTask 'HardwareProfiler'.
2019-10-05 21:05:25.599464 I  Starting HouseKeeper.
2019-10-05 21:05:25.699470 I  Bonjour: Service registration complete: name 'Mythfrontend on mythtv0' type '_mythfrontend._tcp.' domain: 'local.'
2019-10-05 21:05:46.224237 N  Suspending idle timer
2019-10-05 21:05:46.230702 I  TV::TV(): Creating TV object
2019-10-05 21:05:46.259657 N  Suspending idle timer
2019-10-05 21:05:46.262539 I  TV::Init(): Created TvPlayWindow.
2019-10-05 21:05:46.267867 I  TV::HandleStateChange(): Attempting to change from None to WatchingVideo
2019-10-05 21:05:46.285362 I  ALSA: OpenDevice hdmi:CARD=PCH,DEV=0
2019-10-05 21:05:46.288555 I  AOS: Sample rate 32000 is supported
2019-10-05 21:05:46.288567 I  AOS: Sample rate 44100 is supported
2019-10-05 21:05:46.288574 I  AOS: Sample rate 48000 is supported
2019-10-05 21:05:46.288589 I  AOS: Format signed 16 bit is supported
2019-10-05 21:05:46.288617 I  AOS: Format signed 24 bit MSB is supported
2019-10-05 21:05:46.288623 I  AOS: Format signed 32 bit is supported
2019-10-05 21:05:46.288634 I  AOS: 2 channel(s) are supported
2019-10-05 21:05:46.288780 I  ALSA: Successfully retrieved ELD data
2019-10-05 21:05:46.288798 I  ELDUTILS: Detected monitor SAMSUNG at connection type HDMI
2019-10-05 21:05:46.288802 I  ELDUTILS: available speakers: FL/FR
2019-10-05 21:05:46.288806 I  ELDUTILS: max LPCM channels = 2
2019-10-05 21:05:46.288810 I  ELDUTILS: max channels = 2
2019-10-05 21:05:46.288813 I  ELDUTILS: supported codecs = LPCM
2019-10-05 21:05:46.288826 I  ELDUTILS: supports coding type LPCM: channels = 2, rates = 32000 44100 48000, bits = 16 20 24
2019-10-05 21:05:46.297779 I  AOS: may be AC3 or DTS capable
2019-10-05 21:05:46.297789 I  AOS: 6 channel(s) are supported
2019-10-05 21:05:46.299034 N  AudioPlayer: Enabling Audio
2019-10-05 21:05:46.539495 I  AFD: codec AAC has 2 channels
2019-10-05 21:05:46.539943 I  AFD: Opened codec 0x56208a8c5fa0, id(AAC) type(Audio)
2019-10-05 21:05:46.540051 I  AFD: Audio Track #1, of type (Normal) is A/V stream #1 (id=0x2) and has 2 channels in the English language(6647399).
2019-10-05 21:05:46.540058 I  AFD: codec AAC has 2 channels
2019-10-05 21:05:46.540469 I  AFD: Opened codec 0x56208a90ef80, id(AAC) type(Audio)
2019-10-05 21:05:46.540498 I  AFD: Audio Track #2, of type (Normal) is A/V stream #2 (id=0x3) and has 2 channels in the English language(6647399).
2019-10-05 21:05:46.544171 I  AFD: Opened codec 0x56208b3e9520, id(H265) type(Video)
2019-10-05 21:05:46.544199 I  AFD: Trying to select audio track (w/lang)
2019-10-05 21:05:46.544864 I  AFD: Selected track 1: English AAC 2ch (A/V Stream #1)
2019-10-05 21:05:46.544882 I  AFD: Audio data is planar
2019-10-05 21:05:46.544907 I  AFD: Initializing audio parms from audio track #1
2019-10-05 21:05:46.544928 I  AFD: Audio format changed 
			from id(NONE)     -1Hz -1ch -1bps     (profile 0) to id( AAC)  48000Hz  2ch 32bps     (profile 0)
2019-10-05 21:05:46.544950 I  AOBase: Killing AudioOutputDSP
2019-10-05 21:05:46.544963 I  AOBase: Original codec was AAC, 32 bit floating point, 48 kHz, 2 channels
2019-10-05 21:05:46.544978 I  AOBase: enc(0), passthru(0), features (AC3,DTS) configured_channels(2), 2 channels supported(1) max_channels(2)
2019-10-05 21:05:46.544987 I  AOBase: Audio processing enabled
2019-10-05 21:05:46.545000 I  AOBase: Opening audio device 'hdmi:CARD=PCH,DEV=0' ch 2(2) sr 48000 sf signed 32 bit reenc 0
2019-10-05 21:05:46.545006 I  ALSA: OpenDevice hdmi:CARD=PCH,DEV=0
2019-10-05 21:05:46.546939 I  ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=500000, period_time=4)
2019-10-05 21:05:46.547026 I  ALSA: Buffer size range from 32 to 8192
2019-10-05 21:05:46.547032 I  ALSA: Period size range from 16 to 4096
2019-10-05 21:05:46.547050 W  ALSA: Requested 500000us got 170666 buffer time
2019-10-05 21:05:46.547148 I  ALSA: Hardware audio buffer cur: 64 need: 192 max allowed: 32768
2019-10-05 21:05:46.547174 W  ALSA: Try to manually increase audio buffer with: echo 192 | sudo tee /proc/asound/card0/pcm3p/sub0/prealloc
2019-10-05 21:05:46.547186 I  ALSA: Buffer time = 170666 us
2019-10-05 21:05:46.547205 I  ALSA: Period time = 4 periods
2019-10-05 21:05:46.548039 I  ALSA: Buffer size = 8192 | Period size = 2048
2019-10-05 21:05:46.554314 E  ALSA: no playback control PCM found on mixer device default
2019-10-05 21:05:46.554319 E  ALSA: Unable to open audio mixer. Volume control disabled
2019-10-05 21:05:46.554324 I  AOBase: Audio fragment size: 8192
2019-10-05 21:05:46.554341 I  AOBase: Audio Stretch Factor: 1
2019-10-05 21:05:46.554366 I  AOBase: Ending Reconfigure()
2019-10-05 21:05:46.554498 I  AOBase: kickoffOutputAudioLoop: pid = 4114
2019-10-05 21:05:46.554514 I  AOBase: OutputAudioLoop: Play Event
2019-10-05 21:05:46.568152 I  Setup Interrupt handler
2019-10-05 21:05:46.568161 I  Setup Terminated handler
2019-10-05 21:05:46.568203 I  VideoOutputXv: XVideo Adaptor Name: 'GLAMOR Textured Video'
2019-10-05 21:05:46.568210 I  VideoOutput: CalcHueBase(GLAMOR Textured Video): Unknown adaptor, hue may be wrong.
2019-10-05 21:05:46.568213 I  VideoOutput: Please open a ticket if you need to adjust the hue.
2019-10-05 21:05:46.580426 I  OSD: Base theme size: 1280x720
2019-10-05 21:05:46.580441 I  OSD: Scaling factors: 0.5625x0.594444
2019-10-05 21:05:46.608640 I  OSD: Base theme size: 1280x720
2019-10-05 21:05:46.608650 I  OSD: Scaling factors: 0.5625x0.594444
2019-10-05 21:05:46.614935 I  AOBase: OutputAudioLoop: Play Event
2019-10-05 21:05:46.635167 I  Player(0): Video timing method: DRM
2019-10-05 21:05:46.650135 I  TV::StartPlayer(): Created player.
2019-10-05 21:05:46.650161 I  TV::HandleStateChange(): Changing from None to WatchingVideo
2019-10-05 21:05:46.652680 I  TV::HandleStateChange(): Main UI disabled.
2019-10-05 21:05:46.652749 I  TV::StartTV(): Entering main playback loop.
2019-10-05 21:05:46.654167 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.ScreenSaver. cookie 15. nom nom
2019-10-05 21:05:46.682970 C  Received Segmentation fault: Code 128, PID 0, UID 0, Value 0x440044004000400
2019-10-05 21:05:46.783156 I  TV::StartTV(): Exiting main playback loop.
2019-10-05 21:05:46.895180 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:46.995306 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:47.095413 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:47.195520 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:47.295621 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:47.395726 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:47.495834 W  Player(0): Waited 100ms for decoder to pause
2019-10-05 21:05:47.595932 W  Player(0): Waited 100ms for decoder to pause

Re: mkv containers not playing...

Posted: Sat Oct 05, 2019 10:09 pm
by blm-ubunet
I would run FE using debugger but for me that's easy because its built from source... and then find the offending code section.
You can (meaningfully) debug a compiled program if you install the symbols/debug package but that's complicated.
The problem may not be audio at all.

So ramp up the verbose logging to max & hope to find root of the problem & then could restrict logging to an appropriate section.
mythfrontend -v all --loglevel=debug > FE-seqfault-report.txt

Re: mkv containers not playing...

Posted: Sun Oct 06, 2019 2:25 pm
by diyhouse
Tx for you additional comments,.. have run FE in full debug mode,..

Needless to say the file is big,.. but this is where I think it gets interesting... having done a read process,...

it begins doing :-
2019-10-06 09:24:42.018071 D [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 3600 @ 1, ctts: 14400, ctts_count: 237470

these are then repeated until failure... some 4K times,,.. ( I removed some 12K lines of log file... )

I have also copied in the last 20 or so lines just before total failure.........

Your thoughts,.. and anyone else's are welcome,.... ( or have I just treuncated the log too much..

Code: Select all

2019-10-06 09:24:42.008378 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- copying data
2019-10-06 09:24:42.008520 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- checksum 36592
2019-10-06 09:24:42.008621 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) @3905882 -- begin
2019-10-06 09:24:42.008624 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- copying data
2019-10-06 09:24:42.008768 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- checksum 34556
2019-10-06 09:24:42.008868 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) @3938650 -- begin
2019-10-06 09:24:42.008871 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- copying data
2019-10-06 09:24:42.009014 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- checksum 35718
2019-10-06 09:24:42.009117 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/ EnAvengersdgame (2019).m4v): ReadPriv(..32768, normal) @3971418 -- begin
2019-10-06 09:24:42.009119 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- copying data
2019-10-06 09:24:42.009262 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): ReadPriv(..32768, normal) -- checksum 54205
2019-10-06 09:24:42.012854 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): safe_read(...@0, 458752) -- begin
2019-10-06 09:24:42.012865 D  FileRingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): read(458752) -- begin
2019-10-06 09:24:42.012964 D  FileRingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): read(458752) -> 458752 end
2019-10-06 09:24:42.012975 I  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): safe_read(...@0, 458752) -> 458752, took 0 ms (1000Mbps) avg 203 ms
2019-10-06 09:24:42.012980 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): rbwpos += 448K requested 448K in read
2019-10-06 09:24:42.012984 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): total read so far: 2107054788 bytes
2019-10-06 09:24:42.012987 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): @ end of read ahead loop
2019-10-06 09:24:42.018047 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] Offset DTS by ctts[0].duration: 7200
2019-10-06 09:24:42.018055 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] Processing st: 0, edit list 0 - media time: 7200, duration: 938343600
2019-10-06 09:24:42.018058 D  RingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): safe_read(...@458752, 458752) -- begin
2019-10-06 09:24:42.018061 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 0 @ 0, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.018067 D  FileRingBuf(/mnt/Store1/Video1//Movies-Alpha/A-F/freds Play (2019).m4v): read(458752) -- begin
2019-10-06 09:24:42.018071 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 3600 @ 1, ctts: 14400, ctts_count: 237470
2019-10-06 09:24:42.018075 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 7200 @ 2, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.018080 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 10800 @ 3, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.018085 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 14400 @ 4, ctts: 21600, ctts_count: 237470
2019-10-06 09:24:42.018090 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 18000 @ 5, ctts: 10800, ctts_count: 237470
2019-10-06 09:24:42.018094 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 21600 @ 6, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.018100 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 25200 @ 6, ctts: 0, ctts_count: 237470

and just before failure....

2019-10-06 09:24:42.256928 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233420400 @ 59171, ctts: 3600, ctts_count: 237470
2019-10-06 09:24:42.256931 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233424000 @ 59172, ctts: 21600, ctts_count: 237470
2019-10-06 09:24:42.256936 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233427600 @ 59173, ctts: 10800, ctts_count: 237470
2019-10-06 09:24:42.256939 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233431200 @ 59174, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.256943 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233434800 @ 59174, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.256946 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233438400 @ 59175, ctts: 3600, ctts_count: 237470
2019-10-06 09:24:42.256951 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233442000 @ 59176, ctts: 14400, ctts_count: 237470
2019-10-06 09:24:42.256955 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233445600 @ 59177, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.256959 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233449200 @ 59178, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.256963 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233452800 @ 59179, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.256966 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233456400 @ 59180, ctts: 18000, ctts_count: 237470
2019-10-06 09:24:42.256970 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233460000 @ 59181, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.256974 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233463600 @ 59182, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.256978 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233467200 @ 59183, ctts: 3600, ctts_count: 237470
2019-10-06 09:24:42.256981 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233470800 @ 59184, ctts: 18000, ctts_count: 237470
2019-10-06 09:24:42.256985 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233474400 @ 59185, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.256988 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233478000 @ 59186, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.256993 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233481600 @ 59187, ctts: 3600, ctts_count: 237470
2019-10-06 09:24:42.256996 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233485200 @ 59188, ctts: 18000, ctts_count: 237470
2019-10-06 09:24:42.257000 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233488800 @ 59189, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.257004 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233492400 @ 59190, ctts: 0, ctts_count: 237470
2019-10-06 09:24:42.257008 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233496000 @ 59191, ctts: 3600, ctts_count: 237470
2019-10-06 09:24:42.257012 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233499600 @ 59192, ctts: 18000, ctts_count: 237470
2019-10-06 09:24:42.257015 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233503200 @ 59193, ctts: 7200, ctts_count: 237470
2019-10-06 09:24:42.257020 D  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x7f5244045560] shifted frame pts, curr_cts: 233506800 @ 59194, ctts: 0, ctts_count: 237470


Re: mkv containers not playing...

Posted: Sun Oct 06, 2019 7:44 pm
by blm-ubunet
Could be related to those log messages.
Maybe post that log file to pastebin etc.
Take care to strip out IP addresses/server names.

The fatal (stupid) design flaw with MKV is that it only has 1msec time resolution.
It also seems to have bad seek-ability compared to transport streams.
So all the video players have to recreate/recalculate the actual PTS to eliminate frame jitter.

Curiously, I find that MythTV master crashes playing BD rips which are also MKV. But this appears to lock the X/ video driver.

I don't believe your previous "alsa" audio errors are fatal.

Re: mkv containers not playing...

Posted: Sun Oct 06, 2019 9:58 pm
by diyhouse
Ok,.. if I've got this link stuff correct, the google drive link should allow you to download the complete log file....

https://drive.google.com/file/d/1rElw9g ... sp=sharing

Let me know if this works for you...

Many many tx

Re: mkv containers not playing...

Posted: Mon Oct 07, 2019 12:53 am
by blm-ubunet
I don't see anything useful about the crash there.. it just dies.
I would not leave that log file up unless some else needs to see it.

Why so much traffic in logs..UPnP/ dlna stuff?

"Using configuration directory = /homer/"
Is your /home folder really /homer as listed in log?

Did you build myth from source because your install location shows /usr/local
I think you'll have to run FE in gdb to find the problem.

Re: mkv containers not playing...

Posted: Mon Oct 07, 2019 10:27 am
by diyhouse
In a quick response to your comments...

I have no idea why so much UPnP traffic,...

'/homer',.. is not my real 'home' folder... :-)

Yes I built from source..

what do you mean 'FE in gdb' mode... not familiar with this term...
is it worth running the BE with full debug as well,..

Re: mkv containers not playing...

Posted: Mon Oct 07, 2019 8:00 pm
by blm-ubunet
run the FE in the debugger

gdb mythfrontend<enter>
run<enter>
// exercise FE to crash
// cut & paste the last 2-5 pages of terminal to txt file.
quit<enter>

It looked like you had compiled with debug symbol support so this should pin-point the line of code.
There's a whole lot more that's possible but if it's a real bug in MKV PTS code, a developer would want the video file as test case.

Re: mkv containers not playing...

Posted: Tue Oct 08, 2019 8:51 pm
by diyhouse
wow,.. thanks for the introduction on how to run the debug stuff...

the three log file show the output from trying to view run 3 MKV files...
video1,.. works!,.. whilst video2/4.log shows failed mkv viewing...
All files were produced with handbrake, with essentially the same setting... the only difference being the video quality.
video1,.. working version,.. is with a video quality of great than 20,.. I think I set it to 25,.. ( so low video quality )
whilst video 2/4 is with the video set at 17,.. so high quality,... but at the cost of larger file size hence the listing below..

Could their be a limit/timeout on reading the larger mkv files?

-rw-rw-r-- 1 mythtv mythtv 712287135 Oct 8 14:52 AV_video1.mkv
-rw-rw-r-- 1 mythtv mythtv 1896842088 Oct 8 14:55 Av-Video2.mkv
-rw-rw-r-- 1 mythtv mythtv 1638681898 Oct 8 14:57 AV-video3.mkv
-rw-rw-r-- 1 mythtv mythtv 1932891203 Oct 8 15:00 AV-video4.mkv
-rw-rw-r-- 1 mythtv mythtv 2114221538 Oct 8 15:03 AV-video5.m4v

I look forward to any feedback,..
and many thanks for any comments

Re: mkv containers not playing...

Posted: Tue Oct 08, 2019 11:43 pm
by blm-ubunet
I have 10 - 30 GB video files in MKV that used to work in MythTV master on *buntu 14.04 10 months ago but now do not.
It is possible that filesize is a problem in MythTV now..

But I believe your (4) log indicates the seqfault / crash is in ffmpeg HEVC code.
Could try with mythffplay.. but not sure if ffplay handles HEVC either..

I don't see what benefit you get from using HEVC with low resolution content & storage is cheap. I would stick with H264 for a few more years.
HEVC is also not handled well in MythTV.

MythTV uses a private copy of ffmpeg so you can not update.
So I would just stop using HEVC until MythTV performs another ffmpeg refresh cycle.

And I think you should be using OpenGL (vo) instead of XVideo (vo) & using OpenGL or VAAPI (vc) if possible. (or VDPAU on old AMD)

Re: mkv containers not playing...

Posted: Wed Oct 09, 2019 7:47 am
by diyhouse
Thats great,.. or not so,.. you have files they do not play,... interesting,.. that's similar to me as I thought some of my files 'used to play',.. but just put that down to maybe wishful thinking.

The only reason I tried a 'low res' conversion was to see if it worked,.. as I generally try to go with the best resolution I can extract... usually in the 2-5gig range....

I will check my setting,... as you suggest,.. although the log files may be a little miss-leading as I was running the frontend over a remote X terminal,.. so I could monitor/access the command line,.. as well as use the mythtv frontend on another screen...

how long might it be before the internal copy of ffmpeg might get updated,.. is that an in version,.. ie 30 update,. or do we have to wait for a 31 or 32 role, due to dependencies...
But again many tx for your comments and feedback......

Re: mkv containers not playing...

Posted: Wed Oct 09, 2019 7:07 pm
by blm-ubunet
I think my video playback problems are in how MythTV uses the video driver VDPAU API.
It was bulletproof before the last ffmpeg resync.

As you say some of the log content is probably changed by using remote access (SSH or the like).

I can imagine that MythTV devs want to get a new release into *buntu-20.04LTS release cycle & that would likely include a re-sync to latest stable ffmpeg. So in a couple of months in master.
I don't think any ffmpeg fixes are backported except in extreme circumstances.