Opened 13 months ago

Last modified 9 months ago

#13319 new Bug Report - Hang/Deadlock

mythfrontend hangs when exiting LiveTV from an Audio only DVB channel

Reported by: Mike Bibbings Owned by: Peter Bennett
Priority: minor Milestone: unknown
Component: MythTV - Video Playback Version: Master Head
Severity: medium Keywords:
Cc: Ticket locked: no

Description

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

Attachments (1)

mythfrontend.20180906105106.18769.log (317.6 KB) - added by Mike Bibbings 13 months ago.
frontend hang log

Download all attachments as: .zip

Change History (4)

Changed 13 months ago by Mike Bibbings

frontend hang log

comment:1 Changed 13 months ago by 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

comment:2 Changed 13 months ago by 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

comment:3 Changed 9 months ago by Peter Bennett

Milestone: needs_triageunknown
Note: See TracTickets for help on using tickets.