[mythtv-users] Live TV bug.

Tue Jan 19 22:27:40 UTC 2021

Hoi Hika,

Tuesday, January 19, 2021, 11:05:44 PM, you wrote:

> 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

So to recreate it I guess:

1. Start a recording
2. Start watching while the recording still runs
3. Pause watching at the moment the recording finishes
4. Start another recording

Point 4 is implicit when watching livetv

I do not know if both were running on the same tuner. I forgot how to
check what tuner was used on an already finished recording.

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