[mythtv-commits] Ticket #12113: Playback exits early

MythTV noreply at mythtv.org
Wed Apr 9 12:43:26 UTC 2014


#12113: Playback exits early
-----------------------------------------+-------------------------
     Reporter:  jyavenard                |      Owner:  jyavenard
         Type:  Bug Report - General     |     Status:  new
     Priority:  critical                 |  Milestone:  unknown
    Component:  MythTV - Video Playback  |    Version:  Unspecified
     Severity:  medium                   |   Keywords:
Ticket locked:  0                        |
-----------------------------------------+-------------------------
 This occurs at regular intervals, especially playing mkv file (though I
 doubt that this fact is any relevant).

 Playback stops and return to the main menu.

 In the frontend log you read:

 {{{
 2014-04-03 23:06:14.168754 I  AFD: Key frame distance changed from 153 to
 78.
 2014-04-03 23:06:14.797636 E  MythSocket(10da4d040:91): ReadStringList:
 Error, timed out after 7000 ms.
 2014-04-03 23:06:14.797799 E  RemoteFile::Read(): No response from control
 socket.
 2014-04-03 23:06:14.797816 E
 FileRingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 safe_read(RemoteFile* ...): read failed
 2014-04-03 23:06:14.803092 E
 FileRingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 safe_read(RemoteFile* ...): read failed
 2014-04-03 23:06:14.808804 E
 FileRingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 safe_read(RemoteFile* ...): read failed
 2014-04-03 23:06:14.814339 E
 FileRingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 safe_read(RemoteFile* ...): read failed
 2014-04-03 23:06:14.820079 E
 FileRingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 safe_read(RemoteFile* ...): read failed
 2014-04-03 23:06:14.825612 E
 FileRingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 safe_read(RemoteFile* ...): read failed
 2014-04-03 23:06:14.834880 E
 RingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 ReadPriv(..32768, normal): Attempt to read after commserror set
 2014-04-03 23:06:14.835035 E
 RingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 ReadPriv(..32768, normal): Attempt to read after commserror set
 2014-04-03 23:06:14.835044 E
 RingBuf(myth://Videos@192.168.1.40:6543/movies/testing/testing.mkv):
 ReadPriv(..32768, normal): Attempt to read after commserror set
 2014-04-03 23:06:14.835527 E  decoding error
                         eno: Unknown error: 541478725 (541478725)
 2014-04-03 23:06:15.963108 I  Player(0): Video is 3.28031 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-03 23:06:15.988154 I  Player(0): Video is 3.71897 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-03 23:06:15.988262 I  waiting for no video frames 0
 2014-04-03 23:06:15.988269 I  HasReachedEof() at framesPlayed=70840
 totalFrames=135752
 2014-04-03 23:06:16.031928 E  decoding error
                         eno: Unknown error: 541478725 (541478725)
 2014-04-03 23:06:16.042295 I  Player(0): Play speed: rate: 23.976 speed: 0
 skip: 0 => new interval 41708
 2014-04-03 23:06:16.084313 E  decoding error
                         eno: Unknown error: 541478725 (541478725)
 2014-04-03 23:06:16.133934 E  decoding error
                         eno: Unknown error: 541478725 (541478725)
 2014-04-03 23:06:16.184772 E  decoding error
                         eno: Unknown error: 541478725 (541478725)
 2014-04-03 23:06:16.194484 I  TV: HandleStateChange(0) -- begin
 2014-04-03 23:06:16.194508 I  TV: Attempting to change from WatchingVideo
 to None
 2014-04-03 23:06:16.194526 I  TV: StopStuff() for player ctx 0 -- begin
 2014-04-03 23:06:16.194539 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
 2014-04-03 23:06:16.194660 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
 2014-04-03 23:06:16.194681 I  Player(0): StopPlaying - begin
 2014-04-03 23:06:16.195839 I  Player(0): Decoder thread exiting.
 2014-04-03 23:06:16.195934 I  Player(0): Exited decoder loop.
 2014-04-03 23:06:16.204625 I  VideoBuffers::DiscardFrames(1):
 AFAAFAAAAAAFAAAAFFFAAAAAAAAAAFAP
 2014-04-03 23:06:16.204765 I  VideoBuffers::DiscardFrames(1):
 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
 2014-04-03 23:06:16.215078 I  GLVid: Removing master filter
 2014-04-03 23:06:16.216482 I  GLVid: Removing resize filter
 2014-04-03 23:06:16.246082 I  Player(0): StopPlaying - end
 2014-04-03 23:06:16.246101 I  TV: StopStuff(): stopping ring buffer
 2014-04-03 23:06:16.246132 I  TV: StopStuff(): stopping player
 2014-04-03 23:06:16.246140 I  TV: StopStuff() -- end
 2014-04-03 23:06:16.246157 I  TV: Changing from WatchingVideo to None
 2014-04-03 23:06:16.246172 I  TV: HandleStateChange(0) -- end
 2014-04-03 23:06:16.246297 I  TV: Exiting main playback loop.
 2014-04-03 23:06:16.246357 I  TV: StartTV -- process events 2 begin
 2014-04-03 23:06:16.246547 I  TV: StartTV -- process events 2 end
 2014-04-03 23:06:16.246607 I  TV::~TV() -- begin
 2014-04-03 23:06:16.257938 I  TV::~TV() -- lock
 2014-04-03 23:06:16.258743 I  Player(0): StopPlaying - begin
 2014-04-03 23:06:16.258773 I  Player(0): Exited decoder loop.
 2014-04-03 23:06:16.258783 I  Player(0): StopPlaying - end
 2014-04-03 23:06:16.265633 E  MythSocket(10da4d040:-1): WriteStringList:
 Error, called with unconnected socket.
 2014-04-03 23:06:16.265668 E  MythSocket(10da4d040:-1): Failed to send
 command.
 2014-04-03 23:06:16.265672 E  Remote file timeout.
 2014-04-03 23:06:16.266441 I  TV::~TV() -- end
 2014-04-03 23:06:16.267611 N  Resuming idle timer
 2014-04-03 23:06:16.267634 I  TV: StartTV -- end
 }}}

 on the backend:
 {{{
 Apr  3 22:59:26 ananas mythbackend: mythbackend[2753]: I ProcessRequest
 mainserver.cpp:1540 (HandleAnnounce) adding: mozza.home as a remote file
 transfer
 Apr  3 23:00:24 ananas mythbackend: mythbackend[2753]: N DVBRead
 recorders/dtvsignalmonitor.cpp:367 (HandlePMT)
 DTVSigMon[5](/dev/dvb/adapter1/frontend0): PMT says program 1028 is
 encrypted
 Apr  3 23:00:27 ananas mythbackend: mythbackend[2753]: I DVBRead
 mpeg/mpegstreamdata.cpp:1974 (ProcessEncryptedPacket)
 MPEGStream[5](0x7fa7c1522288): PID 0x1af status: Encrypted
 Apr  3 23:00:27 ananas mythbackend: mythbackend[2753]: I DVBRead
 mpeg/mpegstreamdata.cpp:1974 (ProcessEncryptedPacket)
 MPEGStream[5](0x7fa7c1522288): PID 0x1ae status: Encrypted
 Apr  3 23:00:36 ananas mythbackend: mythbackend[2753]: I DVBRead
 mpeg/mpegstreamdata.cpp:1974 (ProcessEncryptedPacket)
 MPEGStream[5](0x7fa7c1522288): PID 0x1a4 status: Encrypted
 Apr  3 23:06:26 ananas mythbackend: mythbackend[2753]: E ProcessRequest
 mainserver.cpp:482 (ProcessRequestWork) ProcessRequest unknown socket
 Apr  3 23:08:13 ananas mythbackend: mythbackend[2753]: N DVBRead
 recorders/dtvsignalmonitor.cpp:367 (HandlePMT)
 DTVSigMon[1](/dev/dvb/adapter0/frontend0): PMT says program 1028 is
 encrypted
 Apr  3 23:08:16 ananas mythbackend: mythbackend[2753]: I DVBRead
 mpeg/mpegstreamdata.cpp:1974 (ProcessEncryptedPacket)
 MPEGStream[1](0x7fa7d8e84bb8): PID 0x1af status: Encrypted
 Apr  3 23:08:16 ananas mythbackend: mythbackend[2753]: I DVBRead
 mpeg/mpegstreamdata.cpp:1974 (ProcessEncryptedPacket)
 MPEGStream[1](0x7fa7d8e84bb8): PID 0x1ae status: Encrypted
 }}}

 nothing showing on the backend really, just an error "ProcessRequest
 mainserver.cpp:482 (ProcessRequestWork) ProcessRequest unknown socket"
 which is well after the frontend stopped playback

--
Ticket URL: <https://code.mythtv.org/trac/ticket/12113>
MythTV <http://www.mythtv.org>
MythTV Media Center


More information about the mythtv-commits mailing list