[mythtv-commits] Ticket #13231: external .srt subtitles not recognized at all for some mkv files
MythTV
noreply at mythtv.org
Thu Mar 1 17:35:58 UTC 2018
#13231: external .srt subtitles not recognized at all for some mkv files
------------------------------------------+------------------------------
Reporter: warpme@… | Owner: pbennett
Type: Bug Report - General | Status: new
Priority: minor | Milestone: needs_triage
Component: MythTV - Video/OSD Rendering | Version: Master Head
Severity: medium | Resolution:
Keywords: srt subtitles | Ticket locked: 0
------------------------------------------+------------------------------
Comment (by warpme@…):
peter, how this?
I have this issue on minimyth2 and also on macOS builds with current
master.
How can I debug this issue?
Here is fe log with playback, audio filters and debug level
{{{
2018-03-01 18:30:52.830958 I Setup Interrupt handler
2018-03-01 18:30:52.831127 I Setup Terminated handler
2018-03-01 18:30:52.831154 I Setup Segmentation fault handler
2018-03-01 18:30:52.831178 I Setup Aborted handler
2018-03-01 18:30:52.831200 I Setup Bus error handler
2018-03-01 18:30:52.831224 I Setup Floating point exception handler
2018-03-01 18:30:52.831246 I Setup Illegal instruction handler
2018-03-01 18:30:52.831274 I Setup Real-time signal 0 handler
2018-03-01 18:30:52.831308 I Setup User defined signal 1 handler
2018-03-01 18:30:52.831332 I Setup User defined signal 2 handler
2018-03-01 18:30:52.831354 I Setup Hangup handler
2018-03-01 18:30:52.831861 C mythfrontend version: master
[v30-Pre-521-gf9c9dd4] www.mythtv.org
2018-03-01 18:30:52.831898 C Qt version: compile: 5.4.1, runtime: 5.4.1
2018-03-01 18:30:52.831910 N Enabled verbose msgs: general
2018-03-01 18:30:52.831989 N Setting Log Level to LOG_INFO
2018-03-01 18:30:52.886490 I Added logging to the console
2018-03-01 18:30:52.904173 N Using runtime prefix = /usr
2018-03-01 18:30:52.904192 N Using configuration directory =
/home/minimyth/.mythtv
2018-03-01 18:30:52.904490 I Assumed character encoding: en_US.UTF-8
2018-03-01 18:30:52.990869 E DBHostName is not set in config.xml
2018-03-01 18:30:52.991124 N Empty LocalHostName.
2018-03-01 18:30:52.991163 I Using localhost value of FE-Livingroom
2018-03-01 18:30:52.991564 I Start up testing connections. DB
192.168.1.254, BE , attempt 0, status dbAwake
2018-03-01 18:30:54.177974 N Setting QT default locale to pl_US
2018-03-01 18:30:54.178042 I Current locale pl_US
2018-03-01 18:30:54.182271 E No locale defaults file for pl_US, skipping
2018-03-01 18:30:54.253006 I Starting process signal handler
2018-03-01 18:30:54.253239 I Starting IO manager (write)
2018-03-01 18:30:54.256348 I Starting process manager
2018-03-01 18:30:54.256648 I Starting IO manager (read)
2018-03-01 18:30:54.303734 I ScreenSaverX11Private: XScreenSaver support
enabled
2018-03-01 18:30:54.305597 I ScreenSaverX11Private: DPMS is disabled.
2018-03-01 18:30:54.469086 N Desktop video mode: 1920x1080 50.000 Hz
2018-03-01 18:30:54.635798 E SG() Error: Could not create builtinStorage
Group directory \'/home/minimyth/.mythtv/tmp/hls\' for \'Streaming\'
2018-03-01 18:30:54.868899 I Listening on TCP 0.0.0.0:6547
2018-03-01 18:30:54.873904 I Listening on TCP [::]:6547
2018-03-01 18:30:55.210330 I Loading pl translation for module
mythfrontend
2018-03-01 18:30:55.216558 N Desktop video mode: 1920x1080 50.000 Hz
2018-03-01 18:30:55.264211 I Using 1920x1080 50.000 Hz
2018-03-01 18:30:55.264238 I SwitchToGUI: Switched to 1920x1080 50.000 Hz
2018-03-01 18:30:55.291584 I LIRC: Successfully initialized \'/dev/lircd\'
using \'/home/minimyth/.mythtv/lircrc\' config
2018-03-01 18:30:55.291746 I UDPListener: Enabling
2018-03-01 18:30:55.294665 I Binding to UDP 0.0.0.0:6948
2018-03-01 18:30:55.294816 I Binding to UDP [::]:6948
2018-03-01 18:30:55.341151 I Using Frameless Window
2018-03-01 18:30:55.341164 I Using Full Screen Window
2018-03-01 18:30:55.380492 I UI Screen Resolution: 1920 x 1080
2018-03-01 18:30:55.395910 I OpenGL: Sync to VBlank is enabled (good!)
2018-03-01 18:30:56.010720 I Trying the OpenGL 2.0 render
2018-03-01 18:30:56.031212 I OpenGL painter using existing OpenGL context.
2018-03-01 18:30:56.080414 I OpenGL2: GLSL supported
2018-03-01 18:30:56.080580 I OpenGL: OpenGL vendor : NVIDIA Corporation
2018-03-01 18:30:56.080593 I OpenGL: OpenGL renderer: ION/PCIe/SSE2
2018-03-01 18:30:56.080604 I OpenGL: OpenGL version : 3.3.0 NVIDIA 340.106
2018-03-01 18:30:56.080640 I OpenGL: Max texture size: 8192 x 8192
2018-03-01 18:30:56.080652 I OpenGL: Max texture units: 4
2018-03-01 18:30:56.080664 I OpenGL: Direct rendering: Yes
2018-03-01 18:30:56.080702 I OpenGL: Extensions Supported: 1e3b
2018-03-01 18:30:56.080712 I OpenGL: PixelBufferObject support available
2018-03-01 18:30:56.387358 I OpenGL: Initialised MythRenderOpenGL
2018-03-01 18:30:56.397222 I MythUIHelper: Pruning cache directory:
/home/minimyth/.mythtv/cache/remotecache is disabled
2018-03-01 18:30:56.397249 I MythUIHelper: Pruning cache directory:
/home/minimyth/.mythtv/cache/thumbnails is disabled
2018-03-01 18:31:04.623186 I MythCoreContext::ConnectCommandSocket():
Connecting to backend server: 192.168.1.254:6543 (try 1 of 1)
2018-03-01 18:31:04.641674 I MythCoreContext::CheckProtoVersion(): Using
protocol version 91 BuzzOff
2018-03-01 18:31:07.020351 W MythCookieJar::load() failed to open file for
reading: /home/minimyth/.mythtv/MythBrowser/cookiejar.txt
2018-03-01 18:31:07.348817 I MythUIWebBrowser: Loading css from -
file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css
2018-03-01 18:31:07.397308 I MythUIWebBrowser: enabling plugins
2018-03-01 18:31:07.686116 I RAOP Device: Created RAOP device objects.
2018-03-01 18:31:07.689609 I Listening on TCP 0.0.0.0:5000
2018-03-01 18:31:07.689726 I Listening on TCP [::]:5000
2018-03-01 18:31:07.689783 I RAOP Device: Listening for connections on
port 5000
2018-03-01 18:31:07.696286 I Registering service 214e624b6333 at MythTV on
FE-Livingroom._raop._tcp port 5000 TXT
tp=UDPsm=falsesv=falseek=1et=0,1cn=0,1ch=2ss=16sr=44100pw=falsevn=3
txtvers=1md=0,1,2vs=115.2da=true
am=MythTV,1
2018-03-01 18:31:07.709180 I Current MythTV Schema Version (DBSchemaVer):
1348
2018-03-01 18:31:08.695371 I Bonjour: Service registration complete: name
\'214e624b6333 at MythTV on FE-Livingroom\' type \'_raop._tcp.\' domain:
\'local.\'
2018-03-01 18:31:09.479763 W Key Alt+V is already bound to a jump point.
2018-03-01 18:31:09.510952 N Registering Internal as a media playback
plugin.
2018-03-01 18:31:09.533801 E MythSystemLegacy(udevinfo) command not
executable,
eno: No such file or directory (2)
2018-03-01 18:31:09.569122 N Registering WebBrowser as a media playback
plugin.
2018-03-01 18:31:09.571017 I Loading pl translation for module mythbrowser
2018-03-01 18:31:09.651167 I Loading pl translation for module mythgame
2018-03-01 18:31:09.760517 I Current MythMusic Schema Version
(MusicDBSchemaVer): 1024
2018-03-01 18:31:09.823704 I Loading pl translation for module mythmusic
2018-03-01 18:31:09.852760 I Loading pl translation for module
mythnetvision
2018-03-01 18:31:09.880359 I Loading pl translation for module mythnews
2018-03-01 18:31:09.927114 I Loading pl translation for module mythweather
2018-03-01 18:31:09.962821 I Connecting to zm server: 192.168.1.254:6548
(try 1 of 2)
2018-03-01 18:31:10.965106 I Using protocol version 11
2018-03-01 18:31:11.010542 N Monitor: Salon (5) is using 3 bytes per pixel
2018-03-01 18:31:11.013292 I Loading pl translation for module
mythzoneminder
2018-03-01 18:31:11.024641 I Listening on TCP 0.0.0.0:6546
2018-03-01 18:31:11.029561 I Listening on TCP [::]:6546
2018-03-01 18:31:11.204749 N Found mainmenu.xml for theme \'Default\'
2018-03-01 18:31:11.243538 I Registering HouseKeeperTask
\'HardwareProfiler\'.
2018-03-01 18:31:11.261069 I Starting HouseKeeper.
2018-03-01 18:31:11.820151 I Bonjour: Service registration complete: name
\'Mythfrontend on FE-Livingroom\' type \'_mythfrontend._tcp.\' domain:
\'local.\'
2018-03-01 18:31:16.588993 I NetworkControl: New connection established.
2018-03-01 18:31:40.447239 I NetworkControl: New connection established.
2018-03-01 18:31:40.448433 N Verbose mask changed, new level is: general
playback audio
2018-03-01 18:31:40.449413 I NetworkControl: Client Socket disconnected
2018-03-01 18:31:44.890568 I NetworkControl: New connection established.
2018-03-01 18:31:44.891512 N Verbose loglevel changed, new loglevel is:
debug
2018-03-01 18:31:44.892367 I NetworkControl: Client Socket disconnected
2018-03-01 18:31:47.697876 N Suspending idle timer
2018-03-01 18:31:47.698415 D MythCoreContext::SendReceiveStringList():
SendReceiveStringList(QUERY_FILE_EXISTS,HD/The Glass Castle
(2017).mkv/VIDEO_TS...) called from UI thread
2018-03-01 18:31:47.699371 D MythCoreContext::SendReceiveStringList():
SendReceiveStringList(QUERY_FILE_EXISTS,HD/The Glass Castle
(2017).mkv/BDMV...) called from UI thread
2018-03-01 18:31:47.714658 N Resuming idle timer
2018-03-01 18:31:50.626537 I TV::TV(): Creating TV object
2018-03-01 18:31:50.752722 I TV::TV(): Finished creating TV object
2018-03-01 18:31:50.752748 D TV::StartTV(): -- begin
2018-03-01 18:31:50.752818 N Suspending idle timer
2018-03-01 18:31:50.752846 D TV::Init(): -- begin
2018-03-01 18:31:50.756860 I TV::Init(): Created TvPlayWindow.
2018-03-01 18:31:50.807711 D TV::DrawUnusedRects(): -- begin
2018-03-01 18:31:50.807796 D TV::DrawUnusedRects(): -- end
2018-03-01 18:31:50.834414 D TV::Init(): -- end
2018-03-01 18:31:50.834494 D TV::StartTV(): tv->Playback() -- begin
2018-03-01 18:31:50.863558 D TV::HandleStateChange(): (0) -- begin
2018-03-01 18:31:50.863603 I TV::HandleStateChange(): Attempting to change
from None to WatchingVideo
2018-03-01 18:31:50.866215 D MythCoreContext::SendReceiveStringList():
SendReceiveStringList(QUERY_FILE_EXISTS,HD/The Glass Castle (2017).mkv...)
called from UI thread
2018-03-01 18:31:50.867851 I FileRingBuf(myth://Videos@mythtv/HD/The Glass
Castle (2017).mkv): OpenFile(myth://Videos@mythtv/HD/The Glass Castle
(2017).mkv, 2000 ms)
2018-03-01 18:31:50.868764 D PortChecker::checkPort(): host 192.168.1.254
port 6543 timeLimit 30000 linkLocalOnly 1
2018-03-01 18:31:50.870973 D PortChecker::checkPort(): host 192.168.1.254
port 6543 timeLimit 30000 linkLocalOnly 1
2018-03-01 18:31:50.880788 D TV::StartPlayer(): (0, WatchingVideo, main)
-- begin
2018-03-01 18:31:50.880876 I TV::StartPlayer(): Elapsed time since TV
constructor was called: 254 ms
2018-03-01 18:31:50.926594 D FilterManager: filters[adjust] = 0x4a07400
2018-03-01 18:31:50.927577 D FilterManager: filters[bobdeint] = 0x60037f0
2018-03-01 18:31:50.928704 D FilterManager: filters[crop] = 0x1232330
2018-03-01 18:31:50.929830 D FilterManager: filters[denoise3d] = 0x64b8cd0
2018-03-01 18:31:50.930687 D FilterManager:
filters[fieldorderdoubleprocessdeint] = 0x627dac0
2018-03-01 18:31:50.931463 D FilterManager: filters[forceyv12] = 0x4a2fd20
2018-03-01 18:31:50.931488 D FilterManager: filters[forceyuv422p] =
0x64d1b70
2018-03-01 18:31:50.931506 D FilterManager: filters[forcergb24] =
0x64d7fb0
2018-03-01 18:31:50.931523 D FilterManager: filters[forceargb32] =
0x62ad3e0
2018-03-01 18:31:50.932838 D FilterManager: filters[greedyhdeint] =
0x6336410
2018-03-01 18:31:50.932864 D FilterManager:
filters[greedyhdoubleprocessdeint] = 0x64d3b50
2018-03-01 18:31:50.933769 D FilterManager: filters[invert] = 0x4a72130
2018-03-01 18:31:50.934577 D FilterManager: filters[ivtc] = 0x645d8e0
2018-03-01 18:31:50.936339 D FilterManager: filters[kerneldeint] =
0x645df30
2018-03-01 18:31:50.936366 D FilterManager:
filters[kerneldoubleprocessdeint] = 0x4a2e330
2018-03-01 18:31:50.937729 D FilterManager: filters[linearblend] =
0x4a2e390
2018-03-01 18:31:50.938613 D FilterManager: filters[onefield] = 0x6331c10
2018-03-01 18:31:50.939890 D FilterManager: filters[postprocess] =
0x63b7210
2018-03-01 18:31:50.941071 D FilterManager: filters[quickdnr] = 0x645d660
2018-03-01 18:31:50.941874 D FilterManager: filters[vflip] = 0x15a14e0
2018-03-01 18:31:50.943283 D FilterManager: filters[yadifdeint] =
0x1f7d920
2018-03-01 18:31:50.943312 D FilterManager:
filters[yadifdoubleprocessdeint] = 0x62acb60
2018-03-01 18:31:51.094398 I ALSA: OpenDevice iec958:CARD=Intel,DEV=0
2018-03-01 18:31:51.105588 I AOS: Sample rate 44100 is supported
2018-03-01 18:31:51.105646 I AOS: Sample rate 48000 is supported
2018-03-01 18:31:51.105674 I AOS: Sample rate 88200 is supported
2018-03-01 18:31:51.105695 I AOS: Sample rate 96000 is supported
2018-03-01 18:31:51.105722 I AOS: Sample rate 192000 is supported
2018-03-01 18:31:51.105791 I AOS: Format signed 16 bit is supported
2018-03-01 18:31:51.105811 I AOS: Format signed 24 bit MSB is supported
2018-03-01 18:31:51.105823 I AOS: Format signed 32 bit is supported
2018-03-01 18:31:51.105876 I AOS: 2 channel(s) are supported
2018-03-01 18:31:51.196833 I AOS: may be AC3 or DTS capable
2018-03-01 18:31:51.196865 I AOS: 6 channel(s) are supported
2018-03-01 18:31:51.207113 N AudioPlayer: Enabling Audio
2018-03-01 18:31:51.852926 I AFD: PlayerFlags: 0xc0, AudioReadAhead: 100
msec
2018-03-01 18:31:51.853013 I Player(0): Stretch Factor 1, allow passthru
2018-03-01 18:31:51.853050 D AFD: OpenFile -- begin
2018-03-01 18:31:51.853866 I AFD: Buffer size: 32768 streamed 0 seekable 1
2018-03-01 18:31:52.436558 I AFD: Stream #0, has id 0x0 codec id H264,
type Video, bitrate 0 at 0x6f7cba0
2018-03-01 18:31:52.436588 I AFD: Stream #1, has id 0x0 codec id AC3, type
Audio, bitrate 640000 at 0x6f7d9a0
2018-03-01 18:31:52.436610 I AFD: codec AC3 has 6 channels
2018-03-01 18:31:52.436622 I AFD: Looking for decoder for AC3
2018-03-01 18:31:52.438030 I AFD: Opened codec 0x7f89680, id(AC3)
type(Audio)
2018-03-01 18:31:52.438180 I AFD: Audio Track #1, of type (Normalny) is
A/V stream #1 (id=0x0) and has 6 channels in the English
language(6647399).
2018-03-01 18:31:52.438208 I AFD: Stream #2, has id 0x0 codec id Unknown
Codec ID, type Subtitle, bitrate 0 at 0x6f7e840
2018-03-01 18:31:52.438221 I AFD: subtitle codec (Subtitle)
2018-03-01 18:31:52.438232 I AFD: Looking for decoder for Unknown Codec ID
2018-03-01 18:31:52.438413 I AFD: Opened codec 0x6fb32c0, id(Unknown Codec
ID) type(Subtitle)
2018-03-01 18:31:52.438442 I AFD: Subtitle track #1 is A/V stream #2 and
is in the English language(6647399).
2018-03-01 18:31:52.438467 I AFD: Stream #3, has id 0x0 codec id Unknown
Codec ID, type Subtitle, bitrate 0 at 0x6f7f6e0
2018-03-01 18:31:52.438479 I AFD: subtitle codec (Subtitle)
2018-03-01 18:31:52.438490 I AFD: Looking for decoder for Unknown Codec ID
2018-03-01 18:31:52.438613 I AFD: Opened codec 0x7d69b20, id(Unknown Codec
ID) type(Subtitle)
2018-03-01 18:31:52.438640 I AFD: Subtitle track #2 is A/V stream #3 and
is in the English language(6647399).
2018-03-01 18:31:52.438651 I AFD: Trying to select best video track
2018-03-01 18:31:52.438786 I AFD: Selected track #0 (id 0x0 codec id H264,
type Video, bitrate 500000 at 0x6f7cba0)
2018-03-01 18:31:52.446134 I VDP: Accepting: cmp() w(>=0) h(>=720)
framerate() codecs() dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau)
osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpauadvanced)
filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2018-03-01 18:31:52.446252 I VDP: Accepting: cmp() w() h() framerate()
codecs() dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau)
osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced)
filt(vdpaucolorspace=auto)
2018-03-01 18:31:52.446307 I VDP: LoadBestPreferences(1920x800, 0.000,
h264)
2018-03-01 18:31:52.446503 I VDP: LoadBestPreferences Result prio:1,
w:>=0, h:>=720, fps:, codecs:, decoder:vdpau, renderer:vdpau,
deint:vdpaubasicdoublerate
2018-03-01 18:31:52.446668 I VDPAU: Checking VDPAU support.
2018-03-01 18:31:52.937966 I VDPAU: Version 1
2018-03-01 18:31:52.937993 I VDPAU: Information NVIDIA VDPAU Driver Shared
Library 340.106 Tue Jan 9 14:26:01 PST 2018
2018-03-01 18:31:52.938042 I VDPAU: HQ scaling level 1 of 9 available.
2018-03-01 18:31:52.938056 I VDPAU: MPEG4 hardware acceleration supported.
2018-03-01 18:31:52.938066 I VDPAU: HEVC hardware acceleration not
supported.
2018-03-01 18:31:52.948443 I AFD: Using 1 CPUs for decoding
2018-03-01 18:31:52.948471 I AFD: InitVideoCodec() 0x7c34dc0 id(H264) type
(Video).
2018-03-01 18:31:52.948523 I AFD: Selected FPS is 23.976 (avg 23.976 codec
0 container 1000 estimated 23.976)
2018-03-01 18:31:52.948580 I Player(0): SetFrameInterval ps:1 scan:3
2018-03-01 18:31:52.948621 I Player(0): detectInterlace(Detect Scan,
Interlaced Scan, 23.976, 800) ->Interlaced Scan
2018-03-01 18:31:52.948649 D AFD: ScanATSCCaptionStreams() called with no
PMT
2018-03-01 18:31:52.948685 I AFD: Using vdpau for video decoding
2018-03-01 18:31:52.948952 I AFD: Opened codec 0x7c34dc0, id(H264)
type(Video)
2018-03-01 18:31:52.962192 I AFD: Selected track 1: English AC3 5.1ch (A/V
Stream #1)
2018-03-01 18:31:52.962324 I AFD: Audio data is planar
2018-03-01 18:31:52.962389 I AFD: Initializing audio parms from audio
track #1
2018-03-01 18:31:52.962451 I AFD: Audio format changed
from id(NONE) -1Hz -1/-1ch -1bps (profile 0) to id( AC3) 48000Hz 6/6ch
32bps pt (profile 0)
2018-03-01 18:31:52.962513 I AOBase: Setting AC3 passthrough
2018-03-01 18:31:52.963155 I SPDIFEncoder: Creating spdif encoder (for
AC3)
2018-03-01 18:31:52.963179 I AOBase: Killing AudioOutputDSP
2018-03-01 18:31:52.963204 I AOBase: Original codec was AC3, 32 bit
floating point, 48 kHz, 6 channels
2018-03-01 18:31:52.963247 I AOBase: enc(0), passthru(1), features
(AC3,DTS) configured_channels(6), 6 channels supported(1) max_channels(6)
2018-03-01 18:31:52.963283 I AOBase: Opening audio device
\'iec958:CARD=Intel,DEV=0\' ch 2(6) sr 48000 sf signed 16 bit reenc 0
2018-03-01 18:31:52.963300 I ALSA: OpenDevice
iec958:CARD=Intel,DEV=0,AES0=6,AES1=0x82,AES2=0x00,AES3=0x01 for
passthrough
2018-03-01 18:31:52.970245 I ALSA: SetParameters(format=2, channels=2,
rate=48000, buffer_time=500000, period_time=4)
2018-03-01 18:31:52.970739 I ALSA: Buffer size range from 64 to 16384
2018-03-01 18:31:52.970761 I ALSA: Period size range from 32 to 8192
2018-03-01 18:31:52.970856 W ALSA: Requested 500000us got 341333 buffer
time
2018-03-01 18:31:52.971189 I ALSA: Hardware audio buffer cur: 64 need: 128
max allowed: 32768
2018-03-01 18:31:52.971266 W ALSA: Try to manually increase audio buffer
with: echo 128 | sudo tee /proc/asound/card0/pcm1p/sub0/prealloc
2018-03-01 18:31:52.971296 I ALSA: Buffer time = 341333 us
2018-03-01 18:31:52.971402 I ALSA: Period time = 4 periods
2018-03-01 18:31:52.983791 I ALSA: Buffer size = 16384 | Period size =
4096
2018-03-01 18:31:52.984052 I AOBase: Audio fragment size: 8192
2018-03-01 18:31:52.984118 I AOBase: Audio Stretch Factor: 1
2018-03-01 18:31:52.984258 I AOBase: Ending Reconfigure()
2018-03-01 18:31:52.984317 I Dec: Selected track #1 (type 2) in the
Unknown language(0)
2018-03-01 18:31:52.984338 I Dec: Trying to select track (w/lang & forced)
2018-03-01 18:31:52.984357 I Dec: Selected track #1 (type 3) in the
English language(6647399)
2018-03-01 18:31:52.984377 I Dec: Resyncing position map. posmapStarted =
0 livetv(0) watchingRec(0)
2018-03-01 18:31:52.984567 I AOBase: kickoffOutputAudioLoop: pid = 8511
2018-03-01 18:31:52.984630 I AOBase: OutputAudioLoop: Play Event
2018-03-01 18:31:52.992392 I Dec: SyncPositionMap prerecorded, from DB: 0
entries
2018-03-01 18:31:52.995534 I AFD: Recording has no position -- using
libavformat seeking.
2018-03-01 18:31:52.996280 I AFD: Successfully opened decoder for file:
"myth://Videos@mythtv/HD/The Glass Castle (2017).mkv". novideo(0)
2018-03-01 18:31:52.996349 I AFD: Chapter 01 found @ [00:00:00.000]->0
2018-03-01 18:31:52.996385 I AFD: Chapter 02 found @ [00:08:15.453]->11878
2018-03-01 18:31:52.996417 I AFD: Chapter 03 found @ [00:15:33.182]->22373
2018-03-01 18:31:52.996449 I AFD: Chapter 04 found @ [00:21:33.292]->31008
2018-03-01 18:31:52.996479 I AFD: Chapter 05 found @ [00:29:25.931]->42340
2018-03-01 18:31:52.996510 I AFD: Chapter 06 found @ [00:37:11.187]->53494
2018-03-01 18:31:52.996541 I AFD: Chapter 07 found @ [00:43:54.840]->63172
2018-03-01 18:31:52.996572 I AFD: Chapter 08 found @ [00:51:53.485]->74648
2018-03-01 18:31:52.996603 I AFD: Chapter 09 found @ [01:00:29.334]->87017
2018-03-01 18:31:52.996634 I AFD: Chapter 10 found @ [01:07:59.659]->97814
2018-03-01 18:31:52.996665 I AFD: Chapter 11 found @
[01:14:05.816]->106592
2018-03-01 18:31:52.996697 I AFD: Chapter 12 found @
[01:24:52.504]->122098
2018-03-01 18:31:52.996728 I AFD: Chapter 13 found @
[01:29:27.696]->128696
2018-03-01 18:31:52.996759 I AFD: Chapter 14 found @
[01:37:47.236]->140672
2018-03-01 18:31:52.996789 I AFD: Chapter 15 found @
[01:45:53.263]->152325
2018-03-01 18:31:52.996819 I AFD: Chapter 16 found @
[01:55:49.484]->166620
2018-03-01 18:31:52.999427 I Using file start
2018-03-01 18:31:53.001902 I VideoOutput: Allowed renderers: vdpau
2018-03-01 18:31:53.001933 I VideoOutput: Allowed renderers (filt: vdpau):
vdpau
2018-03-01 18:31:53.002155 I VDP: Accepting: cmp() w(>=0) h(>=720)
framerate() codecs() dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau)
osd(vdpau) osdfade(enabled) deint(vdpaubasicdoublerate,vdpauadvanced)
filt(vdpaucolorspace=auto,vdpausharpen=1.0)
2018-03-01 18:31:53.002233 I VDP: Accepting: cmp() w() h() framerate()
codecs() dec(vdpau) cpus(4) skiploop(enabled) rend(vdpau) osd(vdpau)
osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced)
filt(vdpaucolorspace=auto)
2018-03-01 18:31:53.002266 I VDP: LoadBestPreferences(1920x800, 23.976,
h264)
2018-03-01 18:31:53.002372 I VDP: LoadBestPreferences Result prio:1,
w:>=0, h:>=720, fps:, codecs:, decoder:vdpau, renderer:vdpau,
deint:vdpaubasicdoublerate
2018-03-01 18:31:53.002398 I VideoOutput: Preferred renderer: vdpau
2018-03-01 18:31:53.002409 I VideoOutput: Trying video renderer: \'vdpau\'
2018-03-01 18:31:53.040527 D ColourSpace: Brightness: 0.0000 Contrast:
1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2018-03-01 18:31:53.040560 D ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2018-03-01 18:31:53.040584 D ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2018-03-01 18:31:53.040609 D ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2018-03-01 18:31:53.040640 D ColourSpace: Brightness: 0.0000 Contrast:
1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2018-03-01 18:31:53.040667 D ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2018-03-01 18:31:53.040692 D ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2018-03-01 18:31:53.040715 D ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2018-03-01 18:31:53.040746 D ColourSpace: Brightness: 0.0000 Contrast:
1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 0
2018-03-01 18:31:53.040772 D ColourSpace: 1.1644 0.0000 1.3416 -0.7465
2018-03-01 18:31:53.040796 D ColourSpace: 1.1644 -0.3289 -0.6828 0.4348
2018-03-01 18:31:53.040819 D ColourSpace: 1.1644 1.6954 0.0000 -0.9241
2018-03-01 18:31:53.040850 D ColourSpace: Brightness: 0.0000 Contrast:
1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 0
2018-03-01 18:31:53.040874 D ColourSpace: 1.1644 0.0000 1.3416 -0.7465
2018-03-01 18:31:53.040899 D ColourSpace: 1.1644 -0.3289 -0.6828 0.4348
2018-03-01 18:31:53.040922 D ColourSpace: 1.1644 1.6954 0.0000 -0.9241
2018-03-01 18:31:53.040952 D ColourSpace: Brightness: 0.0000 Contrast:
1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 1
2018-03-01 18:31:53.040977 D ColourSpace: 1.0000 0.0000 1.1785 -0.5916
2018-03-01 18:31:53.041002 D ColourSpace: 1.0000 -0.2890 -0.5998 0.4461
2018-03-01 18:31:53.041025 D ColourSpace: 1.0000 1.4893 0.0000 -0.7476
2018-03-01 18:31:53.086405 I VideoOutWindow::SetPIPState. pip_state: 0]
2018-03-01 18:31:53.086451 I Clearing OpenGL painter cache.
2018-03-01 18:31:53.262724 I Snapping width to avoid scaling: width: 1920,
left: 0
2018-03-01 18:31:53.262764 I Display Rect left: 0, top: 240, width: 1920,
height: 600, aspect: 1.33333
2018-03-01 18:31:53.262789 I Video Rect left: 0, top: 0, width: 1920,
height: 800, aspect: 2.4
2018-03-01 18:31:53.262808 I Snapping width to avoid scaling: width: 1920,
left: 0
2018-03-01 18:31:53.262831 I Display Rect left: 0, top: 240, width: 1920,
height: 600, aspect: 1.33333
2018-03-01 18:31:53.262855 I Video Rect left: 0, top: 0, width: 1920,
height: 800, aspect: 2.4
2018-03-01 18:31:53.262879 I VDP: SetVideoRenderer(vdpau)
2018-03-01 18:31:53.262897 I VDP: SetVideoRender(vdpau) ==
GetVideoRenderer()
2018-03-01 18:31:53.264032 I Trying to match best refresh rate 23.976Hz
2018-03-01 18:31:53.264078 I Changing to 1920x1080 23.971 Hz
2018-03-01 18:31:53.298116 I Dynamic TwinView rate found, set 23.9709Hz as
XRandR 54
2018-03-01 18:31:53.708490 I SwitchToVideo: Video size 1920 x 800:
Switched to displaying resolution 1920 x 1080, 650mm x 366mm
2018-03-01 18:31:53.708521 I Snapping height to avoid scaling: height:
800, top: 140
2018-03-01 18:31:53.708536 I Snapping width to avoid scaling: width: 1920,
left: 0
2018-03-01 18:31:53.708565 I Display Rect left: 0, top: 140, width: 1920,
height: 800, aspect: 1.77778
2018-03-01 18:31:53.708590 I Video Rect left: 0, top: 0, width: 1920,
height: 800, aspect: 2.4
2018-03-01 18:31:53.708612 I VideoOutput: Pixel dimensions: Screen
1920x1080, window 1920x1080
2018-03-01 18:31:53.708635 I VideoOutput: Actual display dimensions:
650x366 mm Aspect: 1.77596
2018-03-01 18:31:53.708656 I VideoOutput: Estimated window dimensions:
650x366 mm Aspect: 1.77596
2018-03-01 18:31:53.708773 I VidOutVDPAU: VDPAU Sharpen 1.00
2018-03-01 18:31:53.772524 I VDPAU: Created 2 output surfaces.
2018-03-01 18:31:53.772572 I VDPAU: Set colorkey to 0x20202
2018-03-01 18:31:53.772592 I VDPAU: Created VDPAU render device 1920x1080
2018-03-01 18:31:53.784168 I VidOutVDPAU: Created VDPAU osd (1920x1080)
2018-03-01 18:31:54.028784 I ColourSpace: PictureAttributes: Brightness,
Contrast, Colour, Hue, Studio Levels,
2018-03-01 18:31:54.028813 I VidOutVDPAU: Using ITU BT.709 colorspace
2018-03-01 18:31:54.028879 D ColourSpace: Brightness: 0.0000 Contrast:
1.0000 Saturation: 0.8400 Hue: 0.0000 StudioLevels: 1
2018-03-01 18:31:54.028922 D ColourSpace: 1.0000 0.0000 1.3189 -0.6620
2018-03-01 18:31:54.028963 D ColourSpace: 1.0000 -0.1571 -0.3918 0.2755
2018-03-01 18:31:54.029003 D ColourSpace: 1.0000 1.5587 0.0000 -0.7824
2018-03-01 18:31:54.029053 I Snapping height to avoid scaling: height:
800, top: 140
2018-03-01 18:31:54.029075 I Snapping width to avoid scaling: width: 1920,
left: 0
2018-03-01 18:31:54.029111 I Display Rect left: 0, top: 140, width: 1920,
height: 800, aspect: 1.77778
2018-03-01 18:31:54.029151 I Video Rect left: 0, top: 0, width: 1920,
height: 800, aspect: 2.4
2018-03-01 18:31:54.029172 I VidOutVDPAU: Created VDPAU context (GPU
decode)
2018-03-01 18:31:54.035677 I Over/underscan. V: 0, H: 0
2018-03-01 18:31:54.035702 I Snapping height to avoid scaling: height:
800, top: 140
2018-03-01 18:31:54.035716 I Snapping width to avoid scaling: width: 1920,
left: 0
2018-03-01 18:31:54.035743 I Display Rect left: 0, top: 140, width: 1920,
height: 800, aspect: 1.77778
2018-03-01 18:31:54.035767 I Video Rect left: 0, top: 0, width: 1920,
height: 800, aspect: 2.4
2018-03-01 18:31:54.035809 D Player(0): InitFilters() vo
\'vdpaucolorspace=auto,vdpausharpen=1.0\' prog \'\' over \'\'
2018-03-01 18:31:54.035866 I FilterManager: GetFilterInfo(convert)
returning: 0x0
2018-03-01 18:31:54.035909 I Player(0):
LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=1.0\'..) -> 0x0
2018-03-01 18:31:54.041679 I Player(0): Decoder thread starting.
2018-03-01 18:31:54.076587 I OSD: Loaded window osd_message
2018-03-01 18:31:54.094747 I OSD: Loaded window osd_input
2018-03-01 18:31:54.207991 I OSD: Loaded window program_info
2018-03-01 18:31:54.254124 I OSD: Loaded window browse_info
2018-03-01 18:31:54.292391 I OSD: Loaded window osd_status
2018-03-01 18:31:54.405206 I OSD: Loaded window osd_program_editor
2018-03-01 18:31:54.471236 I OSD: Loaded window osd_debug
2018-03-01 18:31:54.471326 I OSD: Loaded OSD: size 1920x1080 offset 0+0
2018-03-01 18:31:54.472102 I OSD: Created window aa_OSD_SUBTITLES
2018-03-01 18:31:54.475499 I EnableCaptions(4) msg: Napisy 1: English WĹÄ
cz
2018-03-01 18:31:54.475557 I Player(0): ClearAfterSeek(0)
2018-03-01 18:31:54.475953 I AOBase: OutputAudioLoop: Play Event
2018-03-01 18:31:54.477219 I VDP: GetFilteredDeint() : vdpau ->
\'vdpaubasicdoublerate\'
2018-03-01 18:31:54.480572 I VidOutVDPAU: Enabled deinterlacing.
2018-03-01 18:31:54.480765 E VSYNC: DRMVideoSync: VBlank ioctl did not
work, unimplemented in this driver?
2018-03-01 18:31:54.480896 E VSYNC: RTCVideoSync: Could not open /dev/rtc:
eno: No such file or directory (2)
2018-03-01 18:31:54.480931 E Player(0): Video sync method can\'t support
double framerate (refresh rate too low for 2x deint)
2018-03-01 18:31:54.491968 I VDP: GetFilteredDeint(vdpauadvanced) : vdpau
-> \'vdpauadvanced\'
2018-03-01 18:31:54.495646 I VidOutVDPAU: Enabled deinterlacing.
2018-03-01 18:31:54.502803 I Player(0): Video timing method: USleep with
busy wait
2018-03-01 18:31:54.502847 I Player(0): Display Refresh Rate: 23.971 Video
Frame Rate: 23.976
2018-03-01 18:31:54.502875 I Player(0): SetFrameInterval ps:1 scan:1
2018-03-01 18:31:54.520450 I playCtx: StartPlaying(): took 0 ms to start
player.
2018-03-01 18:31:54.520497 I TV::StartPlayer(): Created player.
2018-03-01 18:31:54.520540 D TV::StartPlayer(): (0, WatchingVideo, main)
-- end ok
2018-03-01 18:31:54.520628 I TV::HandleStateChange(): Changing from None
to WatchingVideo
2018-03-01 18:31:54.532856 D TV::DrawUnusedRects(): -- begin
2018-03-01 18:31:54.532894 D TV::DrawUnusedRects(): -- end
2018-03-01 18:31:54.532986 I TV::HandleStateChange(): Main UI disabled.
2018-03-01 18:31:54.533011 D TV::HandleStateChange(): (0) -- end
2018-03-01 18:31:54.551543 D TV::StartTV(): tv->Playback() -- end
2018-03-01 18:31:54.554418 I TV::StartTV(): Entering main playback loop.
2018-03-01 18:31:54.626763 D ScreenSaverX11Private: ResetTimer -- begin
2018-03-01 18:31:54.626780 D ScreenSaverX11Private: StopTimer
2018-03-01 18:31:54.630162 D ScreenSaverX11Private: StartTimer
2018-03-01 18:31:54.630240 D ScreenSaverX11Private: ResetTimer -- end
2018-03-01 18:31:54.636838 I Player(0): Waiting for video buffers...
2018-03-01 18:31:54.643172 D TV::DrawUnusedRects(): -- begin
2018-03-01 18:31:54.643211 D TV::DrawUnusedRects(): -- end
2018-03-01 18:31:54.695869 I VidOutVDPAU: Added 3 new buffers. New buffer
size 17 (5 decode and 12 process)
2018-03-01 18:31:54.732801 I VidOutVDPAU: Created VDPAU decoder (5 ref
frames)
2018-03-01 18:31:54.743130 N Player(0): Waited 106ms for video buffers
AAAAAAAAAAAAALLLL
2018-03-01 18:31:54.754124 I Player(0): progressive frame seen after 2
interlaced frames
2018-03-01 18:31:54.754160 I Player(0): A/V predict drop frame, dropping
frame to catch up.
2018-03-01 18:31:54.754249 I Player(0): Waiting for video buffers...
2018-03-01 18:31:54.789856 I VDPAU: Added 2 output surfaces (total 4, max
4)
2018-03-01 18:31:54.871786 I Player(0): Disabled deinterlacing
2018-03-01 18:31:54.874543 I Player(0): Video is 3.61741 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.874574 I AOBase: Pause 1
2018-03-01 18:31:54.874654 I Player(0): Video is 4.48729 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.874669 I AOBase: Pause 1
2018-03-01 18:31:54.874735 I Player(0): Video is 4.88796 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.874749 I AOBase: Pause 1
2018-03-01 18:31:54.874813 I Player(0): Video is 4.9427 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.874826 I AOBase: Pause 1
2018-03-01 18:31:54.874890 I Player(0): Video is 4.73199 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.874903 I AOBase: Pause 1
2018-03-01 18:31:54.874966 I Player(0): Video is 4.32222 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.874980 I AOBase: Pause 1
2018-03-01 18:31:54.875044 I Player(0): Video is 3.76913 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.875057 I AOBase: Pause 1
2018-03-01 18:31:54.875106 I Player(0): Waiting for video buffers...
2018-03-01 18:31:54.875180 D Dec: TranslatePosition(key=3): extrapolating
to (0,0)
2018-03-01 18:31:54.875206 D Dec: TranslatePosition(key=3, ratio=41.7083):
extrapolating to (3,125)
2018-03-01 18:31:54.875221 D Dec: TranslatePosition(key=182601):
extrapolating to (0,0)
2018-03-01 18:31:54.875246 D Dec: TranslatePosition(key=182601,
ratio=41.7083): extrapolating to (182601,7615983)
2018-03-01 18:31:54.880076 I AOBase: OutputAudioLoop: audio paused
2018-03-01 18:31:54.880625 D Dec: TranslatePosition(key=3): extrapolating
to (0,0)
2018-03-01 18:31:54.880657 D Dec: TranslatePosition(key=3, ratio=41.7083):
extrapolating to (3,125)
2018-03-01 18:31:54.880672 D Dec: TranslatePosition(key=182601):
extrapolating to (0,0)
2018-03-01 18:31:54.880697 D Dec: TranslatePosition(key=182601,
ratio=41.7083): extrapolating to (182601,7615983)
2018-03-01 18:31:54.886090 I Player(0): Video is 3.10257 frames behind
audio (too slow), dropping frame to catch up.
2018-03-01 18:31:54.886151 I Player(0): Waiting for video buffers...
2018-03-01 18:31:54.886218 D Dec: TranslatePosition(key=3): extrapolating
to (0,0)
2018-03-01 18:31:54.886244 D Dec: TranslatePosition(key=3, ratio=41.7083):
extrapolating to (3,125)
2018-03-01 18:31:54.886260 D Dec: TranslatePosition(key=182601):
extrapolating to (0,0)
2018-03-01 18:31:54.886284 D Dec: TranslatePosition(key=182601,
ratio=41.7083): extrapolating to (182601,7615983)
2018-03-01 18:31:54.891636 D Dec: TranslatePosition(key=3): extrapolating
to (0,0)
2018-03-01 18:31:54.891664 D Dec: TranslatePosition(key=3, ratio=41.7083):
extrapolating to (3,125)
2018-03-01 18:31:54.891679 D Dec: TranslatePosition(key=182601):
extrapolating to (0,0)
2018-03-01 18:31:54.891703 D Dec: TranslatePosition(key=182601,
ratio=41.7083): extrapolating to (182601,7615983)
2018-03-01 18:31:54.897034 D Dec: TranslatePosition(key=3): extrapolating
to (0,0)
2018-03-01 18:31:54.897062 D Dec: TranslatePosition(key=3, ratio=41.7083):
extrapolating to (3,125)
2018-03-01 18:31:54.897077 D Dec: TranslatePosition(key=182601):
extrapolating to (0,0)
2018-03-01 18:31:54.897100 D Dec: TranslatePosition(key=182601,
ratio=41.7083): extrapolating to (182601,7615983)
2018-03-01 18:31:54.902482 I AOBase: Pause 0
2018-03-01 18:31:54.909367 I AOBase: OutputAudioLoop: Play Event
2018-03-01 18:31:58.656710 I Player(0): FPS: 24.35 Mean: 41075 Std.Dev:
16304 CPUs: 34% 39% 35% 37%
2018-03-01 18:32:02.618857 I Player(0): FPS: 23.98 Mean: 41703 Std.Dev:
109 CPUs: 3% 5% 5% 5%
2018-03-01 18:32:03.996574 I AFD: gopset not set, syncing positionMap
2018-03-01 18:32:03.996608 I Dec: Resyncing position map. posmapStarted =
0 livetv(0) watchingRec(0)
2018-03-01 18:32:03.996622 I AFD: Initial key frame distance: 15.
2018-03-01 18:32:06.581140 I Player(0): FPS: 23.98 Mean: 41704 Std.Dev: 51
CPUs: 2% 5% 4% 6%
2018-03-01 18:32:10.543356 I Player(0): FPS: 23.98 Mean: 41703 Std.Dev: 54
CPUs: 3% 2% 3% 3%
2018-03-01 18:32:11.294058 I Player(0): 400 progressive frames seen.
2018-03-01 18:32:11.335804 D Running HouseKeeper.
2018-03-01 18:32:11.335832 D Checking to run HardwareProfiler
2018-03-01 18:32:14.005572 I AFD: Key frame distance changed from 239 to
240.
2018-03-01 18:32:14.505665 I Player(0): FPS: 23.98 Mean: 41704 Std.Dev: 97
CPUs: 3% 4% 4% 4%
2018-03-01 18:32:17.301442 I AFD: Key frame distance changed from 240 to
79.
2018-03-01 18:32:18.467883 I Player(0): FPS: 23.98 Mean: 41703 Std.Dev: 97
CPUs: 3% 2% 2% 3%
2018-03-01 18:32:21.304046 D LastPlayPos frame=639
2018-03-01 18:32:21.304171 D PortChecker::checkPort(): host 192.168.1.254
port 3306 timeLimit 30000 linkLocalOnly 1
2018-03-01 18:32:21.722371 I AFD: Key frame distance changed from 79 to
106.
2018-03-01 18:32:22.430183 I Player(0): FPS: 23.98 Mean: 41704 Std.Dev: 55
CPUs: 3% 3% 3% 5%
2018-03-01 18:32:23.224926 I Subtitles: Initialised libass object.
2018-03-01 18:32:23.242113 I Subtitles: Initialised libass renderer.
2018-03-01 18:32:23.242369 E libASS log output truncated 255 of 555 bytes
written
2018-03-01 18:32:23.242414 D libass:Raster: FreeType 2.7.1Shaper: FriBidi
0.19.5 (SIMPLE)InitializedEvent: [Script Info]
; Script generated by FFmpeg/Lavc57.107.100
ScriptType: v4.00+
PlayResX: 384
PlayResY: 288
[V4+ Styles]
Format: Name, Fontname, Fontsize, PrimaryColour, SecondaryColour,
OutlineColour, BackColour, Bold, Italic, Underline, Str
2018-03-01 18:32:23.244264 D Style format: Name, Fontname, Fontsize,
PrimaryColour, SecondaryColour, OutlineColour, BackColour, Bold, Italic,
Underline, StrikeOut, ScaleX, ScaleY, Spacing, Angle, BorderStyle,
Outline, Shadow, Alignment, MarginL, MarginR, MarginV, Encoding[0x6298a50]
Style:
Default,Arial,16,&Hffffff,&Hffffff,&H0,&H0,0,0,0,0,100,100,0,0,1,1,0,2,10,10,10,0Name
= DefaultFontName = ArialFontSize = 16PrimaryColour =
&HffffffSecondaryColour = &HffffffOutlineColour = &H0BackColour = &H0Bold
= 0Italic = 0Underline = 0StrikeOut = 0ScaleX = 100ScaleY = 100Spacing =
0Angle = 0BorderStyle = 1Outline = 1Shadow = 0Alignment = 2MarginL =
10MarginR = 10MarginV = 10Encoding = 0Event format: Layer, Start, End,
Style, Name, MarginL, MarginR, MarginV, Effect, TextEvent: Dialogue:
0,0:00:28.57,0:00:31.91,Default,,0,0,0,,{\i1}Rich city folks\Nand their
fancy apartments.{\i0}
2018-03-01 18:32:26.269652 I AFD: Key frame distance changed from 106 to
109.
2018-03-01 18:32:26.309169 I TV::LoadFileHelper(): Loading menu
/home/minimyth/.mythtv/menu_playback.xml
2018-03-01 18:32:26.309291 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/Default/menu_playback.xml
2018-03-01 18:32:26.309397 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/default-wide/menu_playback.xml
2018-03-01 18:32:26.310700 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/default/menu_playback.xml
2018-03-01 18:32:26.315862 I TV::LoadFileHelper(): Loading menu
/home/minimyth/.mythtv/menu_playback_compact.xml
2018-03-01 18:32:26.315933 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/Default/menu_playback_compact.xml
2018-03-01 18:32:26.316012 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/default-wide/menu_playback_compact.xml
2018-03-01 18:32:26.316074 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/default/menu_playback_compact.xml
2018-03-01 18:32:26.316519 I TV::LoadFileHelper(): Loading menu
/home/minimyth/.mythtv/menu_playback.xml
2018-03-01 18:32:26.316573 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/Default/menu_playback.xml
2018-03-01 18:32:26.316613 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/default-wide/menu_playback.xml
2018-03-01 18:32:26.316649 I TV::LoadFileHelper(): Loading menu
/usr/share/mythtv/themes/default/menu_playback.xml
2018-03-01 18:32:26.319919 I AFD: GetCurrentChapter(selected chapter 1
framenum 0)
2018-03-01 18:32:26.394343 I ScreenSaverX11Private: Calling xscreensaver-
command -deactivate
2018-03-01 18:32:26.420932 I Player(0): FPS: 23.81 Mean: 42003 Std.Dev:
8713 CPUs: 7% 4% 11% 5%
2018-03-01 18:32:27.977318 I Player(0): 800 progressive frames seen.
2018-03-01 18:32:29.147441 D Layer = 0Start = 0:00:28.57Duration =
0:00:31.91Style = DefaultName = MarginL = 0MarginR = 0MarginV = 0Effect =
Text = {\i1}Rich city folks\Nand their fancy apartments.{\i0}fontconfig:
Selected font is not the requested one: \'Liberation Sans\' !=
\'Arial\'Font info: family \'Liberation Sans\', style \'Regular\',
fullname \'Liberation Sans\', slant 0, weight 80fontconfig_select: (Arial,
80, 0) -> /usr/share/fonts/X11/TTF/LiberationSans-Regular.ttf,
0fontconfig: Selected font is not the requested one: \'Liberation Sans
Italic\' != \'Arial\'Font info: family \'Liberation Sans\', style
\'Italic\', fullname \'Liberation Sans Italic\', slant 100, weight
80fontconfig_select: (Arial, 80, 110) -> /usr/share/fonts/X11/TTF
/LiberationSans-Italic.ttf, 0forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shifting from 16 to 42
by (-273.453125, 44.406250)forced line break at 15shift
2018-03-01 18:32:29.604108 I AFD: GetCurrentChapter(selected chapter 1
framenum 0)
2018-03-01 18:32:30.354738 I Player(0): FPS: 24.15 Mean: 41404 Std.Dev:
5179 CPUs: 7% 4% 6% 6%
2018-03-01 18:32:31.022921 I AFD: Key frame distance changed from 109 to
114.
2018-03-01 18:32:31.147250 D TV::OSDDialogEvent(): result -1 text action 0
2018-03-01 18:32:33.692232 D LastPlayPos frame=936
2018-03-01 18:32:33.696284 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- begin
2018-03-01 18:32:33.696371 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- end
2018-03-01 18:32:33.734348 D TV::HandleStateChange(): (0) -- begin
2018-03-01 18:32:33.734387 I TV::HandleStateChange(): Attempting to change
from WatchingVideo to None
2018-03-01 18:32:33.734443 D TV::StopStuff(): For player ctx 0 -- begin
2018-03-01 18:32:33.734469 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- begin
2018-03-01 18:32:33.734527 D TV::SetActive(): (0,w/o OSD) 0 -> 0 -- end
2018-03-01 18:32:33.734549 I Player(0): StopPlaying - begin
2018-03-01 18:32:33.745339 I Player(0): Decoder thread exiting.
2018-03-01 18:32:33.745504 I Player(0): Exited decoder loop.
2018-03-01 18:32:33.801494 I VidOutVDPAU: DiscardFrames(1)
2018-03-01 18:32:33.801674 I VideoBuffers::DiscardFrames(1):
UAUUUAUUUAUUUUUAU
2018-03-01 18:32:33.801854 I VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAA -- done
2018-03-01 18:32:33.801901 I VidOutVDPAU: DiscardFrames() 3:
AAAAAAAAAAAAAAAAA -- done()
2018-03-01 18:32:33.807081 W MythPainter: 44 images not yet de-allocated.
2018-03-01 18:32:33.807147 I VDPAU Painter: Clearing VDPAU painter cache.
2018-03-01 18:32:33.915507 I VDPAU Painter: Clearing VDPAU painter cache.
2018-03-01 18:32:33.915580 I Using 1920x1080 23.971 Hz
2018-03-01 18:32:33.915612 I SwitchToGUI: Switched to 1920x1080 23.971 Hz
2018-03-01 18:32:33.915690 I AOBase: Killing AudioOutputDSP
2018-03-01 18:32:33.936102 I AOBase: OutputAudioLoop: Stop Event
2018-03-01 18:32:33.936117 I AOBase: kickoffOutputAudioLoop exiting
2018-03-01 18:32:34.277778 I Player(0): StopPlaying - end
2018-03-01 18:32:34.277799 I TV::StopStuff(): Stopping ring buffer
2018-03-01 18:32:34.277864 I TV::StopStuff(): Stopping player
2018-03-01 18:32:34.277877 D TV::StopStuff(): -- end
2018-03-01 18:32:34.277903 I TV::HandleStateChange(): Changing from
WatchingVideo to None
2018-03-01 18:32:34.277935 D TV::HandleStateChange(): (0) -- end
2018-03-01 18:32:34.277970 I TV::StartTV(): Exiting main playback loop.
2018-03-01 18:32:34.277986 I TV::StartTV(): -- process events 2 begin
2018-03-01 18:32:34.278262 D ScreenSaverX11Private: StopTimer
2018-03-01 18:32:34.279037 I TV::StartTV(): -- process events 2 end
2018-03-01 18:32:34.279062 D TV::~TV(): -- begin
2018-03-01 18:32:34.291657 I TV::~TV(): -- lock
2018-03-01 18:32:34.292450 I Player(0): StopPlaying - begin
2018-03-01 18:32:34.292492 I Player(0): Exited decoder loop.
2018-03-01 18:32:34.292512 I Player(0): StopPlaying - end
2018-03-01 18:32:34.302558 D TV::~TV(): -- end
2018-03-01 18:32:34.304387 N Resuming idle timer
2018-03-01 18:32:34.304433 D TV::StartTV(): -- end
}}}
--
Ticket URL: <https://code.mythtv.org/trac/ticket/13231#comment:2>
MythTV <http://www.mythtv.org>
MythTV Media Center
More information about the mythtv-commits
mailing list