Opened 5 years ago

Closed 5 years ago

#12113 closed Bug Report - General (Fixed)

Playback exits early

Reported by: JYA Owned by: JYA
Priority: critical Milestone: unknown
Component: MythTV - Video Playback Version: Unspecified
Severity: medium Keywords:
Cc: stuartm Ticket locked: no

Description

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

Change History (1)

comment:1 Changed 5 years ago by JYA

Resolution: Fixed
Status: newclosed
Note: See TracTickets for help on using tickets.