[mythtv-users] Playback pause during timeshift when recording finished

[Thomas Börkel]

HI!

Yesterday, I had a brief pause during playback of a timeshifted
recording, when the recording ended.

There was now commercial detection running.

Playback was on a remote frontend via nfs.

Using MythTV 0.26 fixes from February.

>From mythfrontend log:

2013-03-30 17:29:54.558407 I  Player(0): FPS:   25.00 Mean: 39997
Std.Dev:    49 CPUs: 2% 2% 2% 1%
2013-03-30 17:29:58.558341 I  Player(0): FPS:   25.00 Mean: 39997
Std.Dev:    48 CPUs: 2% 4% 0% 1%
2013-03-30 17:30:00.398276 I  Dec: Resyncing position map. posmapStarted
= 0 livetv(0) watchingRec(0)
2013-03-30 17:30:02.187999 I  Dec: Position map filled from DB to: 268360
2013-03-30 17:30:02.188345 I  Dec: SyncPositionMap prerecorded, from DB:
9111 entries
2013-03-30 17:30:02.188353 I  Dec: SyncPositionMap, new totframes:
268360, new length: 10734, posMap size: 9111
2013-03-30 17:30:02.193807 I  New DB connection, total: 4
2013-03-30 17:30:02.195401 I  TV: HandleStateChange(0) -- begin
2013-03-30 17:30:02.195409 I  TV: Attempting to change from
WatchingRecording to WatchingPreRecorded
2013-03-30 17:30:02.195415 I  TV: Changing from WatchingRecording to
WatchingPreRecorded
2013-03-30 17:30:02.195908 I  TV: HandleStateChange(0) -- end
2013-03-30 17:30:02.195918 I  TV: HandleStateChange(0) -- begin
2013-03-30 17:30:02.195936 I  TV: Attempting to change from
WatchingPreRecorded to WatchingPreRecorded
2013-03-30 17:30:02.195942 E  TV: Unknown state transition:
WatchingPreRecorded to WatchingPreRecorded
2013-03-30 17:30:02.196498 I  TV: HandleStateChange(0) -- end
2013-03-30 17:30:02.196841 I  PreviewQueue: Requesting preview for
'2114_20130330133100.mpg_0x0_-1s'
2013-03-30 17:30:02.196875 I  PreviewQueue: Requested preview for
'2114_20130330133100.mpg_0x0_-1s'
2013-03-30 17:30:02.201462 I  Player(0): Video is 3.38063 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201490 I  Player(0): Video is 4.4667 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201509 I  Player(0): Video is 5.03127 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201527 I  Player(0): Video is 5.2047 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201574 I  Player(0): Video is 5.08477 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201594 I  Player(0): Video is 4.74482 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201612 I  Player(0): Video is 4.23985 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201629 I  Player(0): Video is 3.61112 frames behind
audio (too slow), dropping frame to catch up.
2013-03-30 17:30:02.201644 I  Player(0): Waiting for video buffers...
2013-03-30 17:30:02.303244 N  Player(0): Waited 102ms for video buffers
(AL)ALDDAAAfDLAAA
2013-03-30 17:30:02.404912 N  Player(0): Waited 203ms for video buffers
(AL)ALDDAAAfDLAAA
2013-03-30 17:30:02.775959 I  Player(0): Video is 3.54225 frames ahead
of audio,
                        doubling video frame interval to slow down.
2013-03-30 17:30:02.895964 I  Player(0): Video is 3.78793 frames ahead
of audio,
                        doubling video frame interval to slow down.
2013-03-30 17:30:03.015967 I  Player(0): Video is 3.47217 frames ahead
of audio,
                        doubling video frame interval to slow down.
2013-03-30 17:30:04.558299 I  Player(0): FPS:   16.67 Mean: 59997
Std.Dev: 179001 CPUs: 2% 6% 1% 1%
2013-03-30 17:30:08.558149 I  Player(0): FPS:   25.00 Mean: 39996
Std.Dev:    41 CPUs: 4% 1% 1% 1%


I don't watch very often recordings while they are still recording and
mostly I am very near realtime, so it won't finish while I am still
watching.

What's the cause of this effect? Performance problem on the backend?
Using NFS?

Thanks!

Regards,
Thomas