Msg: 'Error was encountered while displaying Msg: 'Error was encountered while displaying video'

[clemens@…]

This problem occurs with 0.19 SVN for versions over the last two weeks or so. It definately occurs in 0.19.2006.0331-1 compiled from SVN on 11may2006

Watching TV localy, things run till about 6sec after the hour, and then get the above message. This SEEMS to be related to the following line in the mythfrontend log file.

2006-05-10 00:00:06.727 RingBuf(/mnt/video/Recordings/1051_20060510000000.mpg): Invalid file (fd -1) \

when opening '/mnt/video/Recordings/1051_20060510000000.mpg'.

The directory is writable, and there is plenty of space, so why the open should fail will take a look at the code itself.

This error is in the current SVN code (as of 11May2006) and has been there for at least a week.

Here are the two files I posted with a message to MythTV-Users on 10may2006

--- Here it is with no options on mythfrontend, note the (fd -1) msg...

2006-05-09 11:30:37.287 Opening OSS audio device '/dev/dsp'.
2006-05-09 11:30:37.290 NVP: Enabling Audio
2006-05-09 11:30:41.563 NVP: prebuffering pause
2006-05-09 12:00:08.053 NVP: prebuffering pause
2006-05-09 12:26:56.512 NVP: prebuffering pause
2006-05-09 12:30:01.879 NVP: prebuffering pause
2006-05-09 12:31:31.279 NVP: prebuffering pause
2006-05-09 13:56:56.388 NVP: prebuffering pause
2006-05-09 13:58:21.656 NVP: prebuffering pause
2006-05-09 13:58:23.055 NVP: Prebuffer wait timed out 10 times.
2006-05-09 13:58:23.540 NVP: prebuffering pause
2006-05-09 13:58:23.752 NVP: prebuffering pause
2006-05-09 13:58:23.969 NVP: prebuffering pause
2006-05-09 13:58:24.172 NVP: prebuffering pause
2006-05-09 13:58:24.373 NVP: prebuffering pause
2006-05-09 13:58:24.580 NVP: prebuffering pause
2006-05-09 13:58:24.786 NVP: prebuffering pause
2006-05-09 13:58:24.989 NVP: prebuffering pause
2006-05-09 13:58:25.193 NVP: prebuffering pause
2006-05-09 14:00:01.966 NVP: prebuffering pause
2006-05-09 14:00:03.369 NVP: Prebuffer wait timed out 10 times.
2006-05-09 14:00:04.769 NVP: Prebuffer wait timed out 10 times.
2006-05-09 14:00:06.169 NVP: Prebuffer wait timed out 10 times.
2006-05-09 14:00:06.965 RingBuf(/mnt/video/Recordings/1051_20060509140000.mpg): Invalid file (fd -1) when opening '/mnt/video/Recordings/1051_20060509140000.mpg'.
2006-05-09 14:00:06.965 NVP, Error: SwitchToProgram's OpenFile failed.
2006-05-09 14:00:06.965 NVP, Error: Unknown error, exiting decoder
2006-05-09 14:00:06.965 TV: Attempting to change from WatchingLiveTV to None
2006-05-09 16:00:24.012 Received a remote 'Clear Cache' request
2006-05-09 18:55:11.044 TV: Changing from WatchingLiveTV to None

===================================

And here we go again, but this time with mythfrontend --verbose playback

'video_output' mean = '33359.45', std. dev. = '1403.98', fps = '29.98'
2006-05-09 23:59:57.896 AFD: positionMap[ 107768 ] == -1456785388.
2006-05-09 23:59:58.396 AFD: positionMap[ 107783 ] == -1456148820.
2006-05-09 23:59:58.896 AFD: positionMap[ 107798 ] == -1454851056.
2006-05-09 23:59:59.397 AFD: positionMap[ 107813 ] == -1453730012.
2006-05-09 23:59:59.898 AFD: positionMap[ 107828 ] == -1452903000.
2006-05-10 00:00:00.400 AFD: positionMap[ 107843 ] == -1451962248.
2006-05-10 00:00:00.538 LiveTVChain(live-mythtv.dwf.com-2006-05-09T19:00:04): ReloadAll(): Added new recording
2006-05-10 00:00:00.538 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2006-05-10 00:00:00.705 NVP: 539200 interlaced frames seen.
'video_output' mean = '33492.42', std. dev. = '2444.33', fps = '29.86'
2006-05-10 00:00:00.932 Position map filled from DB to: 107847
2006-05-10 00:00:00.934 SyncPositionMap watchingrecording, from DB: 7381 entries
2006-05-10 00:00:00.936 SyncPositionMap watchingrecording no entries from encoder, try DB
2006-05-10 00:00:01.013 AFD: positionMap[ 107858 ] == -1450912268.
2006-05-10 00:00:01.147 LiveTVChain(live-mythtv.dwf.com-2006-05-09T19:00:04): SwitchTo(5)
2006-05-10 00:00:01.147 LiveTVChain(live-mythtv.dwf.com-2006-05-09T19:00:04): Entry@5: '1051_20060510000000'
2006-05-10 00:00:01.151 NVP: IsReallyNearEnd() br(500KB) fps(29) sz(0KB) vfl(25) frh(0) ne:1
2006-05-10 00:00:01.151 SwitchToProgram(void)
2006-05-10 00:00:01.227 RingBuf(/mnt/video/Recordings/1051_20060509230000.mpg): OpenFile(myth://204.134.2.12:6543/1051_20060510000000.mpg, 10)
2006-05-10 00:00:01.366 SyncPositionMap watchingrecording total: 7382 entries
2006-05-10 00:00:01.366 SyncPositionMap, new totframes: 107858, new length: 3598, posMap size: 7382
2006-05-10 00:00:01.932 NVP: prebuffering pause
2006-05-10 00:00:01.933 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:01.950 LiveTVChain(live-mythtv.dwf.com-2006-05-09T19:00:04): ReloadAll(): Added new recording
2006-05-10 00:00:02.075 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:02.215 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:02.355 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:02.495 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:02.635 NVP: Waiting for prebuffer.. 5 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:02.775 NVP: Waiting for prebuffer.. 6 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:02.915 NVP: Waiting for prebuffer.. 7 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.055 NVP: Waiting for prebuffer.. 8 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.195 NVP: Waiting for prebuffer.. 9 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.335 NVP: Prebuffer wait timed out 10 times.
2006-05-10 00:00:03.335 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.475 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.615 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.755 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:03.895 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.035 NVP: Waiting for prebuffer.. 5 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.175 NVP: Waiting for prebuffer.. 6 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.315 NVP: Waiting for prebuffer.. 7 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.455 NVP: Waiting for prebuffer.. 8 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.595 NVP: Waiting for prebuffer.. 9 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.735 NVP: Prebuffer wait timed out 10 times.
2006-05-10 00:00:04.735 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:04.875 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.015 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.155 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.295 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.435 NVP: Waiting for prebuffer.. 5 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.575 NVP: Waiting for prebuffer.. 6 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.715 NVP: Waiting for prebuffer.. 7 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.855 NVP: Waiting for prebuffer.. 8 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:05.995 NVP: Waiting for prebuffer.. 9 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.135 NVP: Prebuffer wait timed out 10 times.
2006-05-10 00:00:06.135 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.275 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.415 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.555 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.695 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.727 RingBuf(/mnt/video/Recordings/1051_20060510000000.mpg): Invalid file (fd -1) when opening '/mnt/video/Recordings/1051_20060510000000.mpg'.
2006-05-10 00:00:06.727 RingBuf(/mnt/video/Recordings/1051_20060510000000.mpg): CalcReadAheadThresh(4000 KB)
                         -> threshhold(146 KB) min read(32 KB) blk size(64 KB)
2006-05-10 00:00:06.727 NVP, Error: SwitchToProgram's OpenFile failed.
2006-05-10 00:00:06.728 NVP, Error: Unknown error, exiting decoder
2006-05-10 00:00:06.728 NVP: Exited decoder loop.
2006-05-10 00:00:06.728 TV: Attempting to change from WatchingLiveTV to None
2006-05-10 00:00:06.728 TV: StopStuff() -- begin
2006-05-10 00:00:06.728 TV: StopStuff(): stopping ring buffer[s]
2006-05-10 00:00:06.728 TV: StopStuff(): stopping player[s] (1/2)
2006-05-10 00:00:06.728 TV: StopStuff(): stopping recorder[s]
2006-05-10 00:00:06.857 VideoOutputXv: dtor
2006-05-10 00:00:06.857 VideoBuffers::DiscardFrames(1): AAAAAAAAAAaAALAuAAAAAAAAAAAAAAA
2006-05-10 00:00:06.857 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-05-10 00:00:06.857 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-05-10 00:00:06.869 VideoOutputXv: Closing XVideo port 61
2006-05-10 00:00:06.919 TV: StopStuff(): stopping player[s] (2/2)
2006-05-10 00:35:58.314 TV: StopStuff() -- end
2006-05-10 00:35:58.314 TV: Changing from WatchingLiveTV to None

---

[Isaac Richards]

Need backend logs with -v record at a minimum. Please attach files instead of pasting into the ticket description, too.

[dscoular@…]

mythfrontend and mythbackend logs with requested verbose flags.