mythfrontend hangs when exiting LiveTV from an Audio only DVB channel

[Mike Bibbings]

On exit from LiveTV (pressing Esc key) mythfrontend hangs on Audio only DVB channel.

I get :

2018-09-06 11:51:35.290915 I [18769/18769] CoreContext mythplayer.cpp:2969 (StopPlaying) - Player(0): StopPlaying - begin
2018-09-06 11:51:35.391041 W [18769/18769] CoreContext mythplayer.cpp:3276 (PauseDecoder) - Player(0): Waited 100ms for decoder to pause
2018-09-06 11:51:35.491157 W [18769/18769] CoreContext mythplayer.cpp:3276 (PauseDecoder) - Player(0): Waited 100ms for decoder to pause
....

followed by :

2018-09-06 11:51:45.403119 I [18769/18769] CoreContext mythplayer.cpp:3336 (DecoderEnd) - Player(0): Waited 100ms for decoder loop to stop
2018-09-06 11:51:45.503262 I [18769/18769] CoreContext mythplayer.cpp:3336 (DecoderEnd) - Player(0): Waited 100ms for decoder loop to stop
2018-09-06 11:51:45.603385 I [18769/18769] CoreContext mythplayer.cpp:3336 (DecoderEnd) - Player(0): Waited 100ms for decoder loop to stop
....

followed by :

2018-09-06 11:51:50.409242 E [18769/18769] CoreContext mythplayer.cpp:3339 (DecoderEnd) - Player(0): Failed to stop decoder loop.
2018-09-06 11:51:50.419352 I [18769/18769] CoreContext mythplayer.cpp:5499 (SetDecoder) - Player(0): Waited 10ms for decoder lock
2018-09-06 11:51:50.429461 I [18769/18769] CoreContext mythplayer.cpp:5499 (SetDecoder) - Player(0): Waited 10ms for decoder lock
2018-09-06 11:51:50.439569 I [18769/18769] CoreContext mythplayer.cpp:5499 (SetDecoder) - Player(0): Waited 10ms for decoder lock
....

at this point mythfrontend has to be terminated

Attached mythfrontend log

[Mike Bibbings]

frontend hang log

[jpilk]

I'm not clear if this report is based on one of the new patches, but I see the same with recent unpatched master, v30-Pre-11d426bd49b. I rarely use live tv and so have never encountered it during my normal use. BBC Radio 3, DVB-T with the interactive program info GUI displayed at playback start. It's an easy test, if you can get the signal...

Entering live TV via the Guide and "Watch this channel" then Escape soon after playback starts:

2018-09-06 12:48:33.147229 I  Bonjour: Service registration complete: name 'Mythfrontend on hpbox' type '_mythfrontend._tcp.' domain: 'local.'
2018-09-06 12:49:02.186894 I  TV::TV(): Creating TV object
2018-09-06 12:49:02.219366 N  Suspending idle timer
2018-09-06 12:49:02.222029 I  TV::Init(): Created TvPlayWindow.
2018-09-06 12:49:02.254683 I  TV::HandleStateChange(): Attempting to change from None to WatchingLiveTV
2018-09-06 12:49:02.255922 I  MythCoreContext::ConnectCommandSocket(): Connecting to backend server: 192.168.1.129:6543 (try 1 of 1)
2018-09-06 12:49:02.367466 I  TV::HandleStateChange(): playbackURL(/home/john/SGs/LivetvSG1/1703_20180906114902.ts) inputtype(DUMMY)
2018-09-06 12:49:02.396137 E  ALSA: snd_pcm_info_get_card: Operation not permitted
2018-09-06 12:49:02.414245 N  AudioPlayer: Enabling Audio
2018-09-06 12:49:02.497908 I  Player(0): Video timing method: DRM
2018-09-06 12:49:02.509288 I  TV::StartPlayer(): Created player.
2018-09-06 12:49:02.509359 I  TV::HandleStateChange(): Changing from None to WatchingLiveTV
2018-09-06 12:49:02.509372 I  TV::HandleStateChange(): State is LiveTV & mctx == ctx
2018-09-06 12:49:02.509966 I  TV::HandleStateChange(): UpdateOSDInput done
2018-09-06 12:49:02.509979 I  TV::HandleStateChange(): UpdateLCD done
2018-09-06 12:49:02.510297 I  TV::HandleStateChange(): ITVRestart done
2018-09-06 12:49:02.511153 I  TV::HandleStateChange(): Main UI disabled.
2018-09-06 12:49:02.511188 I  TV::StartTV(): Entering main playback loop.
2018-09-06 12:49:02.518649 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.ScreenSaver. cookie 8079. nom nom
2018-09-06 12:49:02.519136 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.PowerManagement.Inhibit. cookie 443. nom nom
2018-09-06 12:49:02.519333 I  ScreenSaverX11Private: DPMS Deactivated 1
2018-09-06 12:49:06.881871 I  AFD: codec MP2 has 2 channels
2018-09-06 12:49:06.883180 I  AFD: Opened codec 0x4411280, id(MP2) type(Audio)
2018-09-06 12:49:06.885189 E  Player(0): Failed to enable deinterlacing
2018-09-06 12:49:06.885298 I  AOBase: Opening audio device 'default' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2018-09-06 12:49:10.034213 N  TV Play Window Click
2018-09-06 12:49:13.239673 I  TV::HandleStateChange(): Attempting to change from WatchingLiveTV to None
2018-09-06 12:49:13.339833 W  Player(0): Waited 100ms for decoder to pause
2018-09-06 12:49:13.439927 W  Player(0): Waited 100ms for decoder to pause
2018-09-06 12:49:13.540026 W  Player(0): Waited 100ms for decoder to pause
2018-09-06 12:49:13.640122 W  Player(0): Waited 100ms for decoder to pause
2018-09-06 12:49:13.740226 W  Player(0): Waited 100ms for decoder to pause

and so on until Ctrl/C

[Mike Bibbings]

The report is for current master at commit ea500ae, nothing to do with latest vaapi2 testing patches, although that's when I found it and then retested on clean master build just to be sure it was nothing to with the patches.

Mike

[Klaas de Waal]

This problem is still present in today's master. This is the same problem as ticket #13156.

[Stuart Auchterlonie]

Closing all old tickets in trac.

If your issue still persists, please open an issue in Github ​https://github.com/MythTV/mythtv/issues

and reference the existing trac ticket.