[mythtv-users] Live TV bug.

Tue Jan 19 22:05:44 UTC 2021

Hoi Douglas,

Sunday, January 17, 2021, 7:54:35 PM, you wrote:

> On 1/17/21 10:01 AM, Hika van den Hoven wrote:
>> Hoi Douglas,
>>
>> I always, on the rare occasion I use livetv, try to watch a little bit
>> before the live moment. So when I start it, I first pause it for 30
>> seconds or so.
>>
>> At some time long ago I had heard that watching to close to live could
>> give unpredictable results. 
>>
>> Sunday, January 17, 2021, 4:00:54 AM, you wrote:
>>
> What happens when you don't delay is that the picture stutters. If
> you delay once, skipping forward can only get you to within
> about 5 seconds of live, so that problem does not happen.

> I can't remember if the problem occurred the first time I paused,
> or I had paused previously, then got caught up to real time
> during the commercials. I'll try to pay attention to this next time it happens.

OK! I just encountered your phenomena. But it was with a regular
recording that was still recording when I started watching. I'm not
sure if it was still recording when I paused it to make coffee and do
some other things, but I think the pausing and the end of the
recording almost overlapped. Anyway I didn't notice anything then.
I guess I came back some 10 - 15 min later and the bar was completely
green and both counters where running at 4 min and something.
If I deduct that time, another recording then started.

The recording was from a powerrule without any postprocessing jobs or
comercialdetection running

My system is 0.27 on Gentoo and the frontend hasn't been updated in
any way for at least 3 years. The backend had some updates last
summer and myth was then recompiled.

I have never seen it before in the 7 plus years I use MythTV.

My guess is that if I had bookmarked, quited and restarted the
recording I would be at the original pausing moment.

I have a job still attached to some rules that recalculates the
seektable after recording and that creates the opposite effect. The
running bar will then be completely gray without a time running.
Bookmarking works ok, but I must not jump or I'll end up at the start
of the recording. So this effect could be related to the seektables.

Here the frontend log from when I woke it till a few minutes ago:

Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00d4a98047f892499db48af0e3cca3a528 (#-5159)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00dcb71fb1f6cb441d89b5edfe8bf6dd77 (#-5160)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:301 (~SyslogLogger) Removing syslogging
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00d4a98047f892499db48af0e3cca3a528 (#-5161)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00dcb71fb1f6cb441d89b5edfe8bf6dd77 (#-5162)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00d4a98047f892499db48af0e3cca3a528 (#-5163)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00dcb71fb1f6cb441d89b5edfe8bf6dd77 (#-5164)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00d4a98047f892499db48af0e3cca3a528 (#-5165)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1264 (expireClients) Expiring client 00dcb71fb1f6cb441d89b5edfe8bf6dd77 (#-5166)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1373 (forwardMessage) New Client: 00d4a98047f892499db48af0e3cca3a528 (#-5165)
Jan 19 21:59:50 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:295 (SyslogLogger) Added syslogging
Jan 19 21:59:52 [mythlogserver] mythfrontend[32232]: I PreviewGeneratorQueue mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 3
Jan 19 21:59:52 [mythlogserver] mythlogserver[4246]: I LogForward loggingserver.cpp:1373 (forwardMessage) New Client: 00dcb71fb1f6cb441d89b5edfe8bf6dd77 (#-5164)
Jan 19 22:00:01 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:1063 (TV) TV: Creating TV object
Jan 19 22:00:01 [mythlogserver] mythfrontend[32232]: N CoreContext mythmainwindow.cpp:2737 (PauseIdleTimer) Suspending idle timer
Jan 19 22:00:01 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:1280 (Init) TV: Created TvPlayWindow.
Jan 19 22:00:01 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:2225 (HandleStateChange) TV: Attempting to change from None to WatchingRecording
Jan 19 22:00:01 [mythlogserver] mythfrontend[32232]: I CoreContext mythcorecontext.cpp:426 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.222.8:6543 (try 1 of 1)
Jan 19 22:00:02 [mythlogserver] mythfrontend[32232]: N CoreContext audioplayer.cpp:164 (ReinitAudio) AudioPlayer: Enabling Audio
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2141 (ScanStreams) AFD: codec MP2 has 2 channels
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2632 (OpenAVCodec) AFD: Opened codec 0xd9d7360, id(MP2) type(Audio)
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2141 (ScanStreams) AFD: codec MP2 has 2 channels
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2632 (OpenAVCodec) AFD: Opened codec 0xd9d7da0, id(MP2) type(Audio)
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2632 (OpenAVCodec) AFD: Opened codec 0xd9d9280, id(DVB_SUBTITLE) type(Subtitle)
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2632 (OpenAVCodec) AFD: Opened codec 0xd9d9d00, id(DVB_SUBTITLE) type(Subtitle)
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext avformatdecoder.cpp:2632 (OpenAVCodec) AFD: Opened codec 0x46a6680, id(MPEG2VIDEO) type(Video)
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext audio/audiooutputbase.cpp:792 (Reconfigure) AOBase: Opening audio device 'hdmi:CARD=NVidia,DEV=1' ch 2(2) sr 48000 sf signed 32 bit reenc 0
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: E CoreContext audio/audiooutputalsa.cpp:783 (SetParameters) ALSA: Requested 500000us got 170666 buffer time
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: E CoreContext audio/audiooutputalsa.cpp:242 (IncPreallocBufferSize) ALSA: Try to manually increase audio buffer with: echo 192 | sudo tee /proc/asound/card1/pcm7p/sub0/prealloc
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext mythrender_vdpau.cpp:1717 (CreatePresentationSurfaces) VDPAU: Created 2 output surfaces.
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext mythrender_vdpau.cpp:424 (Create) VDPAU: Created VDPAU render device 1920x1080
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: N CoreContext mythplayer.cpp:526 (CheckExtraAudioDecode) Player(1v): Forcing decode extra audio option on (Video method requires it).
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext mythplayer.cpp:1785 (InitAVSync) Player(1v): Video timing method: USleep with busy wait
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:5635 (StartPlayer) TV: Created player.
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:2485 (HandleStateChange) TV: Changing from None to WatchingRecording
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:2576 (HandleStateChange) TV: Main UI disabled.
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:412 (StartTV) TV: Entering main playback loop.
Jan 19 22:00:05 [mythlogserver] mythfrontend[32232]: I CoreContext mythrender_vdpau.cpp:610 (CheckOutputSurfaces) VDPAU: Added 2 output surfaces (total 4, max 4)
Jan 19 22:00:27 [mythlogserver] mythfrontend[32232]: N PreviewGenerator previewgenerator.cpp:399 (RemotePreviewRun) Preview: RemotePreviewRun() -- no reply..
                - Last output repeated 3 times -
Jan 19 22:29:39 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:2225 (HandleStateChange) TV: Attempting to change from WatchingRecording to None
Jan 19 22:29:39 [mythlogserver] mythfrontend[32232]: W CoreContext mythpainter.cpp:36 (Teardown) MythPainter: 4 images not yet de-allocated.
Jan 19 22:29:39 [mythlogserver] mythfrontend[32232]: I CoreContext mythpainter_vdpau.cpp:108 (ClearCache) VDPAU Painter: Clearing VDPAU painter cache.
Jan 19 22:29:39 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:2485 (HandleStateChange) TV: Changing from WatchingRecording to None
Jan 19 22:29:39 [mythlogserver] mythfrontend[32232]: I CoreContext tv_play.cpp:414 (StartTV) TV: Exiting main playback loop.
Jan 19 22:29:39 [mythlogserver] mythfrontend[32232]: N CoreContext mythmainwindow.cpp:2742 (PauseIdleTimer) Resuming idle timer
Jan 19 22:30:15 [mythlogserver] mythfrontend[32232]: N PreviewGenerator previewgenerator.cpp:399 (RemotePreviewRun) Preview: RemotePreviewRun() -- no reply..
                - Last output repeated 2 times -
Jan 19 22:59:50 [mythlogserver] mythfrontend[32232]: N CoreContext mythmainwindow.cpp:2760 (IdleTimeout) Entering standby mode after 30 minutes of inactivity

Tot mails,
  Hika                            mailto:hikavdh at gmail.com

"Zonder hoop kun je niet leven
Zonder leven is er geen hoop
Het eeuwige dilemma
Zeker als je hoop moet vernietigen om te kunnen overleven!"

De lerende Mens