[mythtv-users] Error was encountered while displaying video
Mike Dent
mcdent at gmail.com
Wed Dec 13 08:52:02 UTC 2006
Firstly appologies for the long post.... debug information included!!
On 12/10/06, Mike Dent <mcdent at gmail.com> wrote:
> Hi,
> I'm running a new install of 0.20 on Ubuntu 6.10. I installed this via apt-get.
> After a few hours of playing live TV I sometimes get the error:-
>
> "Error was encountered while displaying video"
>
> I've no idea why this happens, I'm testing the box with a view to
> replacing my Tivo so I'm trying to iron out the bugs before moving it
> in to the lounge!
> I have two WinTV Nova cards in the box.
> When it happened just now I managed to get the last few lines of the
> /var/log/mythtv/mythbackend.log file:-
[older logfile without additional debug snipped out]
>
>
> The box is running an AMD Athlon64 3200, 1gb ram and a Seagate 7200rpm
> 200gb disk.
> Output of df looks like:-
> mike at myth1:/var/lib/mythtv$ df
> Filesystem 1K-blocks Used Available Use% Mounted on
> /dev/sda1 10080488 2273040 7295380 24% /
> varrun 517840 96 517744 1% /var/run
> varlock 517840 0 517840 0% /var/lock
> procbususb 10240 144 10096 2% /proc/bus/usb
> udev 10240 144 10096 2% /dev
> devshm 517840 0 517840 0% /dev/shm
> lrm 517840 17580 500260 4%
> /lib/modules/2.6.17-10-generic/volatile
> /dev/sda3 180932028 33215092 147716936 19% /var/lib
> mike at myth1:/var/lib/mythtv$
>
>
> Any clues as to why this happens every few hours?
>
> thanks
> Mike
>
As suggested I turned on additional debugging for mythtv-backend, specifically
-v record,channel, below is the output of the relevant area of the log file,
everything seemed ok until 03:00.
2006-12-13 02:59:58.970 Writing PAT & PMT @1785711796 + 1767200
2006-12-13 02:59:59.691 Writing PAT & PMT @1786081404 + 1237040
2006-12-13 02:59:59.766 Writing PAT & PMT @6880040292 + 1201696
2006-12-13 03:00:00.294 Writing PAT & PMT @1786448568 + 1625824
2006-12-13 03:00:00.402 Writing PAT & PMT @6880407268 + 1272384
2006-12-13 03:00:00.922 TVRec(1): SwitchLiveTVRingBuffer(discont 0, set_rec 1)
2006-12-13 03:00:00.926 TVRec(1): GetProgramRingBufferForLiveTV()
2006-12-13 03:00:00.970 Writing PAT & PMT @1786816860 + 1449104
2006-12-13 03:00:01.114 TVRec(1): StartedRecording(0x8258248)
fn(/var/lib/mythtv//1082_20061213030001.mpg)
2006-12-13 03:00:01.163 TVRec(1): FinishedRecording(News on the Hour)
in recgroup: LiveTV
2006-12-13 03:00:01.222 Writing PAT & PMT @6880771988 + 1449104
2006-12-13 03:00:01.351 Chain: Updated endtime for
'1082_20061213020000' to 20061213030001
2006-12-13 03:00:01.480 Finished recording News on the Hour: channel 1082
2006-12-13 03:00:01.510 Preview:
'myth://192.168.1.6:6543/1082_20061213020000.mpg' is not local,
replacing with
'/var/lib/mythtv//1082_20061213020000.mpg', which is local.
2006-12-13 03:00:01.550 Chain: Appended at 9 '1082_20061213030001'
2006-12-13 03:00:01.601 DTVRec(1): SetNextRecord(0x8258248, 0x83c3358)
2006-12-13 03:00:01.662 DVBRec(1): 20 PID filters open.
2006-12-13 03:00:01.663 Writing PAT & PMT @1787188160 + 636192
2006-12-13 03:00:02.002 Writing PAT & PMT @6881143852 + 494816
2006-12-13 03:00:02.086 TVRec(1): SetFlags(RingBufferReady,) ->
FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-12-13 03:00:02.314 TVRec(1): !has_rec(1) !rec_soon(1)
curRec(0xab6591b0) starttm(2006-12-13T02:00:00)
2006-12-13 03:00:02.319 TVRec(1): Enabling Full LiveTV UI.
2006-12-13 03:00:02.126 Writing PAT & PMT @1787561340 + 0
[mpegts @ 0xb7387500]2006-12-13 03:00:02.609 DTVRec(1): ResetForNewFile(void)
Parser not found for Codec Id: 94212 !
2006-12-13 03:00:03.002 Writing PAT & PMT @6881501240 + 1802544
[mpegts @ 0xb7387500]2006-12-13 03:00:03.544 TVRec(1):
SwitchLiveTVRingBuffer(discont 0, set_rec 1)
2006-12-13 03:00:03.635 TVRec(1): GetProgramRingBufferForLiveTV()
Parser not found for Codec Id: 94212 !
2006-12-13 03:00:03.734 TVRec(1): StartedRecording(0x826ac60)
fn(/var/lib/mythtv//1082_20061213030003.mpg)
2006-12-13 03:00:03.734 RecBase(0): SetRingBuffer(0x83c3358)
'/var/lib/mythtv//1082_20061213030001.mpg'
2006-12-13 03:00:03.806 TVRec(1): RingBufferChanged()
2006-12-13 03:00:03.778 Writing PAT & PMT @6881870284 + 1767200
2006-12-13 03:00:03.809 TVRec(1): FinishedRecording(News on the Hour)
in recgroup: LiveTV
2006-12-13 03:00:03.844 Chain: Updated endtime for
'1082_20061213030001' to 20061213030003
2006-12-13 03:00:03.933 Finished recording News on the Hour: channel 1082
2006-12-13 03:00:03.966 Preview:
'myth://192.168.1.6:6543/1082_20061213030001.mpg' is not local,
replacing with
'/var/lib/mythtv//1082_20061213030001.mpg', which is local.
2006-12-13 03:00:03.977 Chain: Appended at 10 '1082_20061213030003'
2006-12-13 03:00:03.996 DTVRec(1): SetNextRecord(0x826ac60, 0x8369858)
2006-12-13 03:00:03.999 TVRec(1): SetFlags(RingBufferReady,) ->
FrontendReady,RunMainLoop,CancelNextRecording,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-12-13 03:00:04.000 TVRec(1): !has_rec(1) !rec_soon(1)
curRec(0xab6591b0) starttm(2006-12-13T02:00:00)
2006-12-13 03:00:04.001 TVRec(1): Enabling Full LiveTV UI.
2006-12-13 03:00:04.006 TVRec(1): FinishedRecording(News on the Hour)
in recgroup: LiveTV
2006-12-13 03:00:04.015 Chain: Updated endtime for
'1082_20061213020000' to 20061213030004
2006-12-13 03:00:04.041 Finished recording News on the Hour: channel 1082
2006-12-13 03:00:04.076 DTVRec(1): ResetForNewFile(void)
2006-12-13 03:00:04.078 RecBase(0): SetRingBuffer(0x8369858)
'/var/lib/mythtv//1082_20061213030003.mpg'
2006-12-13 03:00:04.079 TVRec(1): RingBufferChanged()
2006-12-13 03:00:04.082 TVRec(1): FinishedRecording(News on the Hour)
in recgroup: LiveTV
2006-12-13 03:00:04.085 Chain: Updated endtime for
'1082_20061213030001' to 20061213030004
2006-12-13 03:00:04.096 Finished recording News on the Hour: channel 1082
2006-12-13 03:00:04.010 Preview Error: Previewer file
'/var/lib/mythtv//1082_20061213030001.mpg' is not valid.
2006-12-13 03:00:04.178 DVBRec(0): 20 PID filters open.
2006-12-13 03:00:04.307 Writing PAT & PMT @196272 + 706880
2006-12-13 03:00:04.430 Writing PAT & PMT @6882246660 + 636192
2006-12-13 03:00:05.023 Writing PAT & PMT @6882616268 + 565504
2006-12-13 03:00:05.306 Writing PAT & PMT @564188 + 530160
2006-12-13 03:00:05.642 DVBRec(1): 20 PID filters open.
2006-12-13 03:00:05.734 Writing PAT & PMT @6882977792 + 1731856
2006-12-13 03:00:06.181 Writing PAT & PMT @925712 + 1060320
2006-12-13 03:00:06.370 Writing PAT & PMT @6883346460 + 1696512
2006-12-13 03:00:06.918 Writing PAT & PMT @6883719640 + 1413760
0: start_time: 8482.199 duration: 323.989
1: start_time: 8482.241 duration: 323.922
2: start_time: 8482.199 duration: 323.989
3: start_time: 8482.199 duration: 323.989
4: start_time: 8482.199 duration: 323.989
5: start_time: 8482.199 duration: 323.989
stream: start_time: 94246.656 duration: 3599.880 bitrate=3972 kb/s
2006-12-13 03:00:06.959 AFD: Opened codec 0x8245350, id(MPEG2VIDEO) type(Video)
2006-12-13 03:00:06.966 AFD: Opened codec 0x8280320, id(MP3) type(Audio)
2006-12-13 03:00:06.967 AFD: Opened codec 0x83c2a30, id(DVB_SUBTITLE)
type(Subtitle)
2006-12-13 03:00:06.968 AFD: Opened codec 0x83dfae0, id(MP3) type(Audio)
2006-12-13 03:00:06.975 NVP: Recording does not have position map.
Run 'mythcommflag --file
1082_20061213020000.mpg --rebuild' to fix
2006-12-13 03:00:07.019 Writing PAT & PMT @1290432 + 1449104
2006-12-13 03:00:07.534 Writing PAT & PMT @6884090376 + 1131008
2006-12-13 03:00:07.575 DVBRec(0): 20 PID filters open.
2006-12-13 03:00:07.882 Writing PAT & PMT @1659288 + 1060320
2006-12-13 03:00:08.135 Writing PAT & PMT @6884463744 + 176720
2006-12-13 03:00:08.799 Writing PAT & PMT @6884828840 + 671536
2006-12-13 03:00:09.030 Writing PAT & PMT @2017992 + 1661168
2006-12-13 03:00:09.351 DVBRec(1): 20 PID filters open.
2006-12-13 03:00:09.418 Writing PAT & PMT @6885196568 + 954288
2006-12-13 03:00:09.907 Writing PAT & PMT @6885564296 + 1237040
2006-12-13 03:00:10.206 Writing PAT & PMT @2387036 + 35344
2006-12-13 03:00:10.406 Writing PAT & PMT @6885937288 + 706880
2006-12-13 03:00:10.926 Writing PAT & PMT @6886308776 + 565504
2006-12-13 03:00:11.243 DVBRec(0): 20 PID filters open.
2006-12-13 03:00:11.415 Writing PAT & PMT @2743296 + 706880
2006-12-13 03:00:11.486 Writing PAT & PMT @6886676692 + 600848
2006-12-13 03:00:12.086 Writing PAT & PMT @6887047616 + 247408
2006-12-13 03:00:12.458 Writing PAT & PMT @3107452 + 706880
2006-12-13 03:00:12.606 Writing PAT & PMT @6887413276 + 954288
2006-12-13 03:00:12.964 DVBRec(1): 20 PID filters open.
2006-12-13 03:00:12.986 Writing PAT & PMT @3475932 + 883600
2006-12-13 03:00:13.275 Writing PAT & PMT @6887777620 + 1449104
2006-12-13 03:00:13.447 Writing PAT & PMT @3844224 + 1060320
2006-12-13 03:00:13.891 Writing PAT & PMT @6888146100 + 1555136
2006-12-13 03:00:14.178 Writing PAT & PMT @4210824 + 1519792
2006-12-13 03:00:14.458 Writing PAT & PMT @6888514204 + 1767200
2006-12-13 03:00:14.587 DVBRec(0): 20 PID filters open.
2006-12-13 03:00:14.887 Writing PAT & PMT @6888888324 + 1307728
2006-12-13 03:00:14.934 Writing PAT & PMT @4578928 + 1272384
2006-12-13 03:00:15.342 Writing PAT & PMT @6889264324 + 35344
2006-12-13 03:00:15.826 Writing PAT & PMT @4947032 + 600848
2006-12-13 03:00:15.838 Writing PAT & PMT @6889632616 + 671536
2006-12-13 03:00:16.047 DVBRec(1): 20 PID filters open.
2006-12-13 03:00:16.414 Writing PAT & PMT @6890002224 + 600848
2006-12-13 03:00:16.614 Writing PAT & PMT @5315136 + 247408
2006-12-13 03:00:17.099 Writing PAT & PMT @6890368636 + 671536
2006-12-13 03:00:17.210 Writing PAT & PMT @5678540 + 1131008
2006-12-13 03:00:17.695 DVBRec(0): 20 PID filters open.
2006-12-13 03:00:17.779 Writing PAT & PMT @6051532 + 424128
2006-12-13 03:00:17.834 StopLiveTV(void) curRec: 0xab65a030 pseudoRec: 0
2006-12-13 03:00:17.841 TVRec(1): Changing from WatchingLiveTV to None
2006-12-13 03:00:17.853 TVRec(1):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-12-13 03:00:17.856 TVRec(1): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-12-13 03:00:17.856 TVRec(1): Request: Program(no) channel()
input() flags(KillRec,KillRingBuffer,)
2006-12-13 03:00:17.879 DVBRec(0): Close() fd(19) -- begin
2006-12-13 03:00:18.158 Writing PAT & PMT @6890732980 + 424128
2006-12-13 03:00:18.294 DVBRec(0): Close() fd(-1) -- end
2006-12-13 03:00:18.486 TVRec(1): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-12-13 03:00:18.493 DVBRec(0): SetOutputPAT(NULL)
2006-12-13 03:00:18.494 DVBRec(0): SetOutputPMT(NULL)
2006-12-13 03:00:18.499 TVRec(1): FinishedRecording(News on the Hour)
in recgroup: LiveTV
2006-12-13 03:00:18.502 Chain: Updated endtime for
'1082_20061213030003' to 20061213030018
2006-12-13 03:00:18.520 Finished recording News on the Hour: channel 1082
2006-12-13 03:00:18.526 TVRec(1): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-12-13 03:00:18.527 TVRec(1): Tearing down RingBuffer
2006-12-13 03:00:18.529 TVRec(1): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-12-13 03:00:18.530 StopLiveTV(void) curRec: 0x846c348 pseudoRec: 0
2006-12-13 03:00:18.539 TVRec(2): Changing from WatchingLiveTV to None
2006-12-13 03:00:18.546 TVRec(2):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-12-13 03:00:18.547 TVRec(2): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-12-13 03:00:18.548 TVRec(2): Request: Program(no) channel()
input() flags(KillRec,KillRingBuffer,)
2006-12-13 03:00:18.562 DVBRec(1): Close() fd(43) -- begin
2006-12-13 03:00:18.902 DVBRec(1): Close() fd(-1) -- end
2006-12-13 03:00:19.078 TVRec(2): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-12-13 03:00:19.085 DVBRec(1): SetOutputPAT(NULL)
2006-12-13 03:00:19.086 DVBRec(1): SetOutputPMT(NULL)
2006-12-13 03:00:19.092 TVRec(2): FinishedRecording(Through the Night)
in recgroup: LiveTV
2006-12-13 03:00:19.094 Chain: Updated endtime for
'1083_20061212230002' to 20061213030019
2006-12-13 03:00:19.112 Finished recording Through the Night: channel 1083
2006-12-13 03:00:19.118 TVRec(2): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-12-13 03:00:19.119 TVRec(2): Tearing down RingBuffer
2006-12-13 03:00:19.121 TVRec(2): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-12-13 03:02:54.715 Expiring News on the Hour from Wed Dec 13
03:00:00 2006, 5 MBytes, forced expire (LiveTV recording)
2006-12-13 04:00:57.744 Expiring Through the Night from Mon Dec 11
23:00:00 2006, 7037 MBytes, forced expire (LiveTV recording)
2006-12-13 05:02:07.990 Expiring News on the Hour from Wed Dec 13
03:00:00 2006, 0 MBytes, forced expire (LiveTV recordi
Any help is very welcome,
thanks!
Mike
More information about the mythtv-users
mailing list