[mythtv-users] 10 sec delay exiting playback in 0.28

John Lundell jdlundell at gmail.com
Thu May 5 18:55:11 UTC 2016 

Hi All,

I just upgraded to 0.28 and have a new issue that when exiting video
playback (recordings or videos, hitting the exit button or hitting the end
of the recording) the screen freezes and nothing happens for about 10
secs.  When running with -v all I do not see anything in the log which
would indicate what is going on. You can see a whole bunch of things happen
at 11:44:28 to stop the playback and then really not a much until the video
actually stops at 11:44:38, ten seconds later.

I have tried different themes and different decoders (normal, vaapi and
here xv) and all produce them same delay.  I man running on a I5-3450 using
intel graphics.  Also the frontend process seems to be idle during the
delay so it looks like it is waiting for something to time out.

Any ideas or suggestions?

John

--------------------log------------------------------------
2016-05-05 11:44:28.260218 I  TV::HandleStateChange(): Attempting to change
from WatchingRecording to None
2016-05-05 11:44:28.260257 I  Player(0): StopPlaying - begin
2016-05-05 11:44:28.262031 I  AFD: video timecode 266065 2956 2956 2939
2016-05-05 11:44:28.263124 I  Player(0): Decoder thread exiting.
2016-05-05 11:44:28.263174 I  Player(0): Exited decoder loop.
2016-05-05 11:44:28.263549 I  MythSocket(7f349400e9a0:45): write -> 45 77
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED aa_OSD_SUBTITLES S...
2016-05-05 11:44:28.263807 I  (0x7f34800040f0)::DecrRef() -> 1
2016-05-05 11:44:28.263820 I  (0x7f34840035c0)::DecrRef() -> 1
2016-05-05 11:44:28.263865 I  (0x7f3480089d50)::DecrRef() -> 1
2016-05-05 11:44:28.263873 I  (0x7f348411c800)::DecrRef() -> 1
2016-05-05 11:44:28.264093 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.264121 I  VideoOutputXv: dtor
2016-05-05 11:44:28.264133 I  MythSocket(7f349400e9a0:45): write -> 45 72
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED browse_info SENDER...
2016-05-05 11:44:28.264150 I  VideoOutputXv: DiscardFrames(1)
2016-05-05 11:44:28.264203 I  VideoBuffers::DiscardFrames(1):
AUUUUUUUUUUUUUUUUUUUUUUUUUUUUUUP
2016-05-05 11:44:28.264256 I  VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2016-05-05 11:44:28.264273 I  VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2016-05-05 11:44:28.264287 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.264329 I  MythSocket(7f349400e9a0:45): write -> 45 70
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED osd_debug SENDER m...
2016-05-05 11:44:28.264488 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.264524 I  (0x357b4a0)::DecrRef() -> 0
2016-05-05 11:44:28.264519 I  MythSocket(7f349400e9a0:45): write -> 45 70
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED osd_input SENDER m...
2016-05-05 11:44:28.264549 I  (0x2da2b80)::DecrRef() -> 0
2016-05-05 11:44:28.264559 I  (0x4adaa90)::DecrRef() -> 0
2016-05-05 11:44:28.264566 I  (0x48f2c90)::DecrRef() -> 0
2016-05-05 11:44:28.264574 I  (0x4db9f20)::DecrRef() -> 0
2016-05-05 11:44:28.264661 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.264696 I  MythSocket(7f349400e9a0:45): write -> 45 72
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED osd_message SENDER...
2016-05-05 11:44:28.264825 I  AOBase: Killing AudioOutputDSP
2016-05-05 11:44:28.264836 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.264878 I  MythSocket(7f349400e9a0:45): write -> 45 79
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED osd_program_editor...
2016-05-05 11:44:28.265002 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.265034 I  MythSocket(7f349400e9a0:45): write -> 45 71
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED osd_status SENDER ...
2016-05-05 11:44:28.265153 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.265186 I  MythSocket(7f349400e9a0:45): write -> 45 73
   MESSAGE[]:[]SYSTEM_EVENT SCREEN_TYPE DESTROYED program_info SENDE...
2016-05-05 11:44:28.265305 I  MythSocket(7f349400e9a0:45): read  <- 45 2
    OK
2016-05-05 11:44:28.265626 I  AOBase: OutputAudioLoop: Stop Event
2016-05-05 11:44:28.265632 I  AOBase: kickoffOutputAudioLoop exiting
2016-05-05 11:44:29.337696 I  MythSocket(7f349401f440:47): read  <- 47 61
   BACKEND_MESSAGE[]:[]UPDATE_FILE_SIZE 4856 398818124[]:[]empty
2016-05-05 11:44:29.337710 I  MythCoreContext::dispatch(): MythEvent:
UPDATE_FILE_SIZE 4856 398818124
2016-05-05 11:44:29.939210 I  MythSocket(7f349401f440:47): read  <- 47 62
   BACKEND_MESSAGE[]:[]UPDATE_FILE_SIZE 4855 1255508180[]:[]empty
2016-05-05 11:44:29.939252 I  MythCoreContext::dispatch(): MythEvent:
UPDATE_FILE_SIZE 4855 1255508180
2016-05-05 11:44:31.448644 I  MythSocket(7f349401f440:47): read  <- 47 62
   BACKEND_MESSAGE[]:[]UPDATE_FILE_SIZE 4854 5152630304[]:[]empty
2016-05-05 11:44:31.448669 I  MythCoreContext::dispatch(): MythEvent:
UPDATE_FILE_SIZE 4854 5152630304
2016-05-05 11:44:32.884679 I  MythSocket(7f349401f440:47): read  <- 47 98
   BACKEND_MESSAGE[]:[]FILE_WRITTEN /mythData3/recordings/1091_20160...
2016-05-05 11:44:32.884725 I  MythCoreContext::readyRead(): Received remote
'FILE_WRITTEN /mythData3/recordings/1091_20160505183000.ts' request
2016-05-05 11:44:33.730057 I  MythSocket(7f349401f440:47): read  <- 47 97
   BACKEND_MESSAGE[]:[]FILE_WRITTEN /mythData1/recordings/4315_20160...
2016-05-05 11:44:33.730079 I  MythCoreContext::readyRead(): Received remote
'FILE_WRITTEN /mythData1/recordings/4315_20160505183000.ts' request
2016-05-05 11:44:33.757741 I  MythSocket(7f349401f440:47): read  <- 47 98
   BACKEND_MESSAGE[]:[]FILE_WRITTEN /mythData2/recordings/1051_20160...
2016-05-05 11:44:33.757761 I  MythCoreContext::readyRead(): Received remote
'FILE_WRITTEN /mythData2/recordings/1051_20160505180000.ts' request
2016-05-05 11:44:36.838292 I  MythSocket(7f349400e9a0:45): write -> 45 21
   QUERY_FREE_SPACE_LIST
2016-05-05 11:44:36.838880 I  MythSocket(7f349400e9a0:45): read  <- 45 384
    mymyth[]:[]mymyth:/mythData1/recordings[]:[]1[]:[]1[]:[]2[]:[]409...
2016-05-05 11:44:38.324998 I  Player(0): StopPlaying - end
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20160505/cb48f0be/attachment.html>

More information about the mythtv-users mailing list