View unanswered posts    View active topics

Board index Linux MythTV

All times are UTC - 6 hours





Post new topic Reply to topic  Page 1 of 1
  [ 7 posts ] 
Print view Previous topic   Next topic  
Author Message
Search for:
mihanson
PostPosted: Sat Dec 23, 2006 7:10 pm 
Offline
Joined: Sun Sep 25, 2005 3:50 pm
Posts: 1013
Location: Los Angeles
Hi all,
I'm having a problem with the frontend of my combined FE/BE locking up regularily as of late. The specs for said system are in my signature. I'll add that I'm running the final version of mythtv-0.19 from SVN and NVIDIA driver 8774. I get the lockup when I first select Live TV or after a channel change. This box has been purring for a few months until about a week ago. I have not altered anything in the meantime. I can SSH into the box when it locks up and top tells me XFree86 is using 99% of my CPU. I can restart GDM via SSH to "fix" the issue, but it just comes right back. At the end of my XFree86.0.log I have the following errors:
Code:
(WW) NVIDIA(0): WAIT (2, 10, 0x8000, 0x000098d0, 0x0000a6a0, 0)
(WW) NVIDIA(0): WAIT (1, 10, 0x8000, 0x000098d0, 0x0000a6a0, 0)
(WW) NVIDIA(0): WAIT (2, 10, 0x8000, 0x000098d0, 0x0000a6d4, 0)
(WW) NVIDIA(0): WAIT (1, 10, 0x8000, 0x000098d0, 0x0000a6d4, 0)
(WW) NVIDIA(0): WAIT (2, 10, 0x8000, 0x000098d0, 0x0000a708, 0)
(WW) NVIDIA(0): WAIT (1, 10, 0x8000, 0x000098d0, 0x0000a708, 0)
(WW) NVIDIA(0): WAIT (2, 10, 0x8000, 0x000098d0, 0x0000a73c, 0)
(WW) NVIDIA(0): WAIT (1, 10, 0x8000, 0x000098d0, 0x0000a73c, 0)
(WW) NVIDIA(0): WAIT (2, 4, 0x8000, 0x000098d0, 0x0000a7a8, 0)
(WW) NVIDIA(0): WAIT (1, 4, 0x8000, 0x000098d0, 0x0000a7a8, 0)
(WW) NVIDIA(0): WAIT (2, 4, 0x8000, 0x000098d0, 0x0000a7d8, 0)
(WW) NVIDIA(0): WAIT (1, 4, 0x8000, 0x000098d0, 0x0000a7d8, 0)


There's nothing in the backend log.

Here's a snippit from my syslog. This entry is written at the time of the lockup.

Code:
Dec 22 08:35:00 mythbox kernel: NVRM: Xid (0001:00): 13, 0000 01014200 00000062 00000400 00ffffff 00000a00


I decided to run mythfrontend from a X Term and this is a sample of what I get:

Code:
2006-12-23 11:57:49.774 Using runtime prefix = /usr
2006-12-23 11:57:49.934 New DB connection, total: 1
2006-12-23 11:57:49.964 Connected to database 'mythconverg' at host: 192.168.11.4
2006-12-23 11:57:49.965 Total desktop dim: 1280x720, with 1 screen[s].
2006-12-23 11:57:49.985 Using screen 0, 1280x720 at 0,0
2006-12-23 11:57:49.990 Current Schema Version: 1123
2006-12-23 11:57:49.991 mythfrontend version: 0.19.20060121-2 www.mythtv.org
2006-12-23 11:57:49.991 Enabled verbose msgs:  important general
2006-12-23 11:57:50.348 lang0: eng
2006-12-23 11:57:50.348 lang: eng
2006-12-23 11:57:50.356 lang1: spa
2006-12-23 11:57:50.356 lang: spa
2006-12-23 11:57:50.500 Total desktop dim: 1280x720, with 1 screen[s].
2006-12-23 11:57:50.501 Using screen 0, 1280x720 at 0,0
2006-12-23 11:57:50.502 Switching to square mode (blue)
2006-12-23 11:57:50.826 New DB connection, total: 2
2006-12-23 11:57:50.826 Connected to database 'mythconverg' at host: 192.168.11.4
2006-12-23 11:57:50.831 Key P is bound to multiple actions in context TV Playback.
2006-12-23 11:57:50.835 Registering Internal as a media playback plugin.
2006-12-23 11:57:50.918 Registering MythDVD DVD Media Handler as a media handler
2006-12-23 11:57:50.918 Registering MythDVD VCD Media Handler as a media handler
2006-12-23 11:57:51.962 Registering MythMusic Media Handler as a media handler
2006-12-23 11:57:52.336 Starting media monitor.
2006-12-23 11:57:52.338 NetworkControl: Listening for remote connections on port 6546
2006-12-23 11:57:55.320 Connecting to backend server: 192.168.11.4:6543 (try 1 of 5)
2006-12-23 11:57:55.326 Using protocol version 26
2006-12-23 11:57:55.429 TV: Attempting to change from None to WatchingLiveTV
2006-12-23 11:57:55.440 Using protocol version 26
2006-12-23 11:57:59.692 RingBuf(/myth/tv/1055_20061223115755.mpg): Waited 2 seconds for data to become available...
2006-12-23 11:57:59.692 Checking to see if there's a new livetv program to switch to..
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 11:57:59.985 AFD: Opened codec 0x8233ab0, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 11:57:59.985 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 11:57:59.985 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 11:58:00.210 TV: Changing from None to WatchingLiveTV
2006-12-23 11:58:00.211 New DB connection, total: 3
2006-12-23 11:58:00.212 New DB connection, total: 4
2006-12-23 11:58:00.212 Connected to database 'mythconverg' at host: 192.168.11.4
2006-12-23 11:58:00.213 Connected to database 'mythconverg' at host: 192.168.11.4
2006-12-23 11:58:00.216 Using realtime priority.
2006-12-23 11:58:00.394 Video timing method: USleep with busy wait
2006-12-23 11:58:00.454 VideoOutputXv Error: ProcessFrameXvMC: Failed to get OSD lock
0: start_time: 8527.645 duration: 0.486
1: start_time: 8527.599 duration: 0.475
stream: start_time: 94751.097 duration: 5.915 bitrate=3603 kb/s
2006-12-23 11:58:04.405 VideoOutputXv Error: Child     A        was already marked as available.
<snip>
2006-12-23 11:58:04.517 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 11:58:04.518 AFD: Opened codec 0x8f62550, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 11:58:04.519 AFD: Opened codec 0x8e18880, id(AC3) type(Audio)
2006-12-23 11:58:04.521 Opening audio device '/dev/dsp'.
2006-12-23 11:58:04.521 Opening OSS audio device '/dev/dsp'.
2006-12-23 11:58:04.528 NVP: Enabling Audio
2006-12-23 11:58:04.563 VideoOutputXv Error: Child     A        was already marked as available.
<snip>
2006-12-23 11:58:04.649 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 11:58:18.304 rebuffering (3941 32768)
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 11:58:19.406 AFD: Opened codec 0xb3a22340, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 11:58:19.407 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 11:58:19.426 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 11:58:21.690 NVP: Timed out waiting for free video buffers.
<snip>
2006-12-23 11:58:46.001 NVP: Timed out waiting for free video buffers.
2006-12-23 11:58:48.495 rebuffering (7985 32768)
2006-12-23 11:58:48.833 VideoOutputXv Error: Child     A        was already marked as available.
<snip>
2006-12-23 11:58:48.956 VideoOutputXv Error: Child     A        was already marked as available.
0: start_time: 3888.103 duration: 2.664
1: start_time: 3888.070 duration: 2.673
stream: start_time: 43200.773 duration: 29.965 bitrate=15385 kb/s
2006-12-23 11:58:48.959 AFD: Opened codec 0x82508e0, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 11:58:48.960 AFD: Opened codec 0x8218d80, id(AC3) type(Audio)
2006-12-23 11:58:48.960 Opening audio device '/dev/dsp'.
2006-12-23 11:58:48.960 Opening OSS audio device '/dev/dsp'.
2006-12-23 11:58:48.962 NVP: Enabling Audio
2006-12-23 11:58:48.963 VideoOutputXv Error: Child     A        was already marked as available.
<snip>
2006-12-23 11:58:49.042 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 11:58:50.234 AddInheritence past     E    NOT in used or in done. (AL)AAAaAL
2006-12-23 11:58:50.241 AddInheritence past     E    NOT in used or in done. (AL)LAAaAA
2006-12-23 11:58:50.248 NVP::AddAudioData():p1: Audio buffer overflow, audio data lost!
<snip>
2006-12-23 11:58:51.481 NVP::AddAudioData():p1: Audio buffer overflow, audio data lost!
2006-12-23 11:58:51.481 AddInheritence past     E    NOT in used or in done. (AL)LAAaAA
2006-12-23 11:58:51.488 NVP::AddAudioData():p1: Audio buffer overflow, audio data lost!
2006-12-23 11:58:51.488 AddInheritence past     E    NOT in used or in done. ALALaAA
2006-12-23 11:58:51.495 NVP::AddAudioData():p1: Audio buffer overflow, audio data lost!
<snip>
2006-12-23 11:58:52.608 NVP::AddAudioData():p1: Audio buffer overflow, audio data lost!
2006-12-23 12:07:59.888 TV: Attempting to change from WatchingLiveTV to None
2006-12-23 12:08:00.602 TV: Changing from WatchingLiveTV to None
0: start_time: 5013.925 duration: 653.029
1: start_time: 5013.900 duration: 653.023
stream: start_time: 55710.003 duration: 7256.158 bitrate=10658 kb/s
2006-12-23 12:08:05.091 AFD: Opened codec 0x9147630, id(MPEG2VIDEO) type(Video)
2006-12-23 12:08:05.103 AFD: Opened codec 0x8ed81c0, id(AC3) type(Audio)
0: start_time: 5013.925 duration: 653.029
1: start_time: 5013.900 duration: 653.023
stream: start_time: 55710.003 duration: 7256.158 bitrate=10658 kb/s
2006-12-23 12:08:07.518 AFD: Opened codec 0x8ed81c0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:08:07.519 AFD: Opened codec 0x9147630, id(AC3) type(Audio)
2006-12-23 12:08:20.824 TV: Attempting to change from None to WatchingLiveTV
2006-12-23 12:08:20.831 Using protocol version 26
2006-12-23 12:08:24.483 RingBuf(/myth/tv/1021_20061223120821.mpg): Waited 2 seconds for data to become available...
2006-12-23 12:08:24.484 Checking to see if there's a new livetv program to switch to..
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:08:24.807 AFD: Opened codec 0x9147630, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:08:24.808 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:08:24.808 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 12:08:24.973 TV: Changing from None to WatchingLiveTV
2006-12-23 12:08:24.973 Using realtime priority.
2006-12-23 12:08:25.321 Video timing method: USleep with busy wait
2006-12-23 12:08:25.399 VideoOutputXv Error: ProcessFrameXvMC: Failed to get OSD lock
0: start_time: 3942.472 duration: 0.486
1: start_time: 3942.432 duration: 0.495
stream: start_time: 43804.805 duration: 5.846 bitrate=17788 kb/s
2006-12-23 12:08:28.810 AFD: Opened codec 0xaa82f720, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:08:28.811 AFD: Opened codec 0xaa44db50, id(AC3) type(Audio)
2006-12-23 12:08:28.811 Opening audio device '/dev/dsp'.
2006-12-23 12:08:28.811 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:08:28.829 NVP: Enabling Audio
2006-12-23 12:08:28.901 VideoOutputXv Error: Child     A        was already marked as available.
<snip>
2006-12-23 12:08:28.976 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:22:31.286 TV: Attempting to change from WatchingLiveTV to None
2006-12-23 12:22:31.851 TV: Changing from WatchingLiveTV to None
2006-12-23 12:23:02.812 Received a remote 'Clear Cache' request
2006-12-23 12:23:05.109 TV: Attempting to change from None to WatchingLiveTV
2006-12-23 12:23:05.115 Using protocol version 26
2006-12-23 12:23:09.214 RingBuf(/myth/tv/1021_20061223122305.mpg): Waited 2 seconds for data to become available...
2006-12-23 12:23:09.215 Checking to see if there's a new livetv program to switch to..
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:23:09.407 AFD: Opened codec 0x8ed81c0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:23:09.407 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:23:09.407 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 12:23:09.814 TV: Changing from None to WatchingLiveTV
2006-12-23 12:23:09.816 Using realtime priority.
2006-12-23 12:23:10.188 Video timing method: USleep with busy wait
0: start_time: 4022.067 duration: 0.444
1: start_time: 4022.047 duration: 0.438
stream: start_time: 44689.413 duration: 5.154 bitrate=18384 kb/s
2006-12-23 12:23:12.852 AFD: Opened codec 0xaa8a2ad0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:23:12.856 AFD: Opened codec 0xaa44db50, id(AC3) type(Audio)
2006-12-23 12:23:12.857 Opening audio device '/dev/dsp'.
2006-12-23 12:23:12.858 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:23:12.887 NVP: Enabling Audio
2006-12-23 12:23:13.887 NVP: prebuffering pauseAAAAAAAAAAAAAAAAAAAAAAAAAAaAuL
2006-12-23 12:23:14.447 NVP: prebuffering pauseAAAAAAAAAuAALLAAAAAAAAAAAAAAAA
2006-12-23 12:23:57.001 NVP: prebuffering pauseAAAAAAAAAAAAAAAAAAAAAAAAAAuAAL
2006-12-23 12:23:58.562 NVP: Prebuffer wait timed out 10 times.
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:23:59.036 AFD: Opened codec 0xaa8a1260, id(MPEG2VIDEO) type(Video)
2006-12-23 12:23:59.036 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:23:59.036 NVP: Disabling Audio, params(0,-1,-1)
0: start_time: 1415.790 duration: 0.219
1: start_time: 1415.772 duration: 0.207
stream: start_time: 15730.798 duration: 2.642 bitrate=13172 kb/s
2006-12-23 12:24:02.149 NVP: prebuffering pauseAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-12-23 12:24:02.256 AFD: Opened codec 0xb3a43ab0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:24:02.257 AFD: Opened codec 0xaf017f10, id(AC3) type(Audio)
2006-12-23 12:24:02.257 Opening audio device '/dev/dsp'.
2006-12-23 12:24:02.257 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:24:02.273 NVP: Enabling Audio
2006-12-23 12:24:44.355 TV: Attempting to change from WatchingLiveTV to None
2006-12-23 12:24:44.876 TV: Changing from WatchingLiveTV to None
2006-12-23 12:25:04.217 Received a remote 'Clear Cache' request
2006-12-23 12:25:06.129 TV: Attempting to change from None to WatchingLiveTV
2006-12-23 12:25:06.135 Using protocol version 26
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:25:07.712 AFD: Opened codec 0x9147630, id(MPEG2VIDEO) type(Video)
2006-12-23 12:25:07.712 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:25:07.712 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 12:25:08.253 TV: Changing from None to WatchingLiveTV
2006-12-23 12:25:08.261 Using realtime priority.
2006-12-23 12:25:08.731 Video timing method: USleep with busy wait
0: start_time: 1422.052 duration: 0.242
1: start_time: 1422.052 duration: 0.242
stream: start_time: 15800.573 duration: 2.686 bitrate=14769 kb/s
2006-12-23 12:25:11.788 NVP: prebuffering pauseAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-12-23 12:25:12.016 AFD: Opened codec 0x8d5e8e0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:25:12.017 AFD: Opened codec 0x90f28a0, id(AC3) type(Audio)
2006-12-23 12:25:12.019 Opening audio device '/dev/dsp'.
2006-12-23 12:25:12.019 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:25:12.043 NVP: Enabling Audio
2006-12-23 12:25:12.882 NVP: prebuffering pauseAAAAAAAAAAAAAAAAAuAAALALAAAAAA
2006-12-23 12:25:13.265 NVP: prebuffering pauseAAAAuALALLAAAAAAAAAAAAAAAAAAAA
2006-12-23 12:25:34.033 NVP: prebuffering pauseAAAAAAAAAAAAAAAAUaLAAAALAAAAAA
2006-12-23 12:25:34.948 NVP: Prebuffer wait timed out 10 times.
2006-12-23 12:25:35.738 NVP: Prebuffer wait timed out 10 times.
2006-12-23 12:25:36.203 rebuffering (31505 32768)
2006-12-23 12:25:36.418 NVP: Prebuffer wait timed out 10 times.
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:25:37.165 NVP: Prebuffer wait timed out 10 times.
2006-12-23 12:25:37.293 AFD: Opened codec 0xb3a43ab0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:25:37.293 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:25:37.296 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 12:25:38.539 NVP: prebuffering pauseAAAAAAAAAAAAAAAAALUALAAAAALAAA
2006-12-23 12:25:38.933 NVP: prebuffering pauseAAAAAUAALLLAAAAAAAAAAAAAAAAAAA
2006-12-23 12:25:39.572 NVP: prebuffering pauseAAAAAAAAAUALLLAAAAAAAAAAAAAAAA
0: start_time: 6860.898 duration: 0.222
1: start_time: 6860.849 duration: 0.207
stream: start_time: 76231.655 duration: 3.015 bitrate=10626 kb/s
2006-12-23 12:25:39.760 AFD: Opened codec 0xaf14d0e0, id(MPEG2VIDEO) type(Video)
2006-12-23 12:25:39.761 AFD: Opened codec 0xaefd7210, id(AC3) type(Audio)
2006-12-23 12:25:39.761 Opening audio device '/dev/dsp'.
2006-12-23 12:25:39.761 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:25:39.763 NVP: Enabling Audio
2006-12-23 12:25:40.110 NVP: prebuffering pause(UL)AAAAAAAAAAAAALaAAAAALAALLAAAA
2006-12-23 12:25:40.542 NVP: prebuffering pause(AL)AuAAAAALAAAAALaLAAAAAALAAAAAA
2006-12-23 12:25:44.582 NVP: prebuffering pause(AL)AAAAAuLAAAAAALaLAAAAAAAAAALAA
2006-12-23 12:25:53.824 TV: Attempting to change from WatchingLiveTV to None
2006-12-23 12:25:54.429 TV: Changing from WatchingLiveTV to None
2006-12-23 12:26:23.447 Received a remote 'Clear Cache' request
2006-12-23 12:26:26.466 TV: Attempting to change from None to WatchingLiveTV
2006-12-23 12:26:26.472 Using protocol version 26
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:26:28.463 AFD: Opened codec 0x9147630, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:26:28.463 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:26:28.463 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 12:26:28.733 TV: Changing from None to WatchingLiveTV
2006-12-23 12:26:28.734 The realtime priority setting is not enabled.
2006-12-23 12:26:29.122 Video timing method: USleep with busy wait
2006-12-23 12:26:29.220 VideoOutputXv Error: ProcessFrameXvMC: Failed to get OSD lock
0: start_time: 6865.628 duration: 0.285
1: start_time: 6865.572 duration: 0.276
stream: start_time: 76284.135 duration: 3.788 bitrate=6934 kb/s
2006-12-23 12:26:33.246 AFD: Opened codec 0xaa82c4e0, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:26:33.247 AFD: Opened codec 0xaa82b160, id(AC3) type(Audio)
2006-12-23 12:26:33.249 Opening audio device '/dev/dsp'.
2006-12-23 12:26:33.249 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:26:33.257 NVP: Enabling Audio
2006-12-23 12:26:33.321 VideoOutputXv Error: Child           g  was already marked as available.
<snip>
2006-12-23 12:26:33.389 VideoOutputXv Error: Child           g  was already marked as available.
2006-12-23 12:26:40.432 VideoOutputXv Error: Child          f   was already marked as available.
<snip>
2006-12-23 12:26:41.181 VideoOutputXv Error: Child          f   was already marked as available.
2006-12-23 12:26:41.185 rebuffering (12265 32768)
2006-12-23 12:26:41.188 VideoOutputXv Error: Child          f   was already marked as available.
<snip>
2006-12-23 12:26:41.865 VideoOutputXv Error: Child          f   was already marked as available.
0: start_time: 5859.060 duration: -9223372036854.775
stream: start_time: 65100.668 duration: -9223372036854.775 bitrate=0 kb/s
2006-12-23 12:26:41.871 AFD: Opened codec 0xb3a43ab0, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:26:41.871 NVP: Disabling Audio, params(-1,-1,-1)
2006-12-23 12:26:41.872 VideoOutputXv Error: Child          f   was already marked as available.
2006-12-23 12:26:41.874 NVP: Disabling Audio, params(0,-1,-1)
2006-12-23 12:26:41.879 VideoOutputXv Error: Child          f   was already marked as available.
2006-12-23 12:26:41.886 VideoOutputXv Error: Child          f   was already marked as available.
2006-12-23 12:26:41.896 VideoOutputXv Error: Child          f   was already marked as available.
0: start_time: 4041.346 duration: 0.324
1: start_time: 4041.320 duration: 0.323
stream: start_time: 44903.557 duration: 3.890 bitrate=17771 kb/s
2006-12-23 12:26:45.589 AFD: Opened codec 0xaf017f10, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:26:45.590 AFD: Opened codec 0xb3a0e130, id(AC3) type(Audio)
2006-12-23 12:26:45.590 Opening audio device '/dev/dsp'.
2006-12-23 12:26:45.590 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:26:45.592 NVP: Enabling Audio
2006-12-23 12:26:45.667 VideoOutputXv Error: Child            h was already marked as available.
2006-12-23 12:26:45.667 VideoOutputXv Error: Child            h was already marked as available.
<snip>
2006-12-23 12:26:45.742 VideoOutputXv Error: Child            h was already marked as available.
2006-12-23 12:38:07.795 TV: Attempting to change from WatchingLiveTV to None
2006-12-23 12:38:08.403 TV: Changing from WatchingLiveTV to None
0: start_time: 5013.925 duration: 653.029
1: start_time: 5013.900 duration: 653.023
stream: start_time: 55710.003 duration: 7256.158 bitrate=10658 kb/s
2006-12-23 12:39:52.446 AFD: Opened codec 0x914ef50, id(MPEG2VIDEO) type(Video)
2006-12-23 12:39:52.447 AFD: Opened codec 0x8f87b40, id(AC3) type(Audio)
0: start_time: 5013.925 duration: 653.029
1: start_time: 5013.900 duration: 653.023
stream: start_time: 55710.003 duration: 7256.158 bitrate=10658 kb/s
2006-12-23 12:39:52.949 AFD: Opened codec 0x914ef50, id(MPEG2VIDEO) type(Video)
2006-12-23 12:39:52.949 AFD: Opened codec 0x8d70c70, id(AC3) type(Audio)
2006-12-23 12:40:04.152 TV: Attempting to change from None to WatchingPreRecorded
0: start_time: 5013.925 duration: 653.029
1: start_time: 5013.900 duration: 653.023
stream: start_time: 55710.003 duration: 7256.158 bitrate=10658 kb/s
2006-12-23 12:40:04.351 AFD: Opened codec 0x90ebf00, id(MPEG2VIDEO_XVMC) type(Video)
2006-12-23 12:40:04.351 AFD: Opened codec 0x8218d10, id(AC3) type(Audio)
2006-12-23 12:40:04.351 Opening audio device '/dev/dsp'.
2006-12-23 12:40:04.351 Opening OSS audio device '/dev/dsp'.
2006-12-23 12:40:05.347 The realtime priority setting is not enabled.
2006-12-23 12:40:05.385 TV: Changing from None to WatchingPreRecorded
2006-12-23 12:40:05.679 Video timing method: USleep with busy wait
2006-12-23 12:40:07.366 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:40:07.366 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:40:07.366 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:40:07.376 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:50:18.210 AddInheritence past    D     NOT in used or in done. DAAaDLLP
2006-12-23 12:50:18.236 AddInheritence past    D     NOT in used or in done. DDLaALAP
2006-12-23 13:02:13.711 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:13.742 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:13.743 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:13.762 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:13.776 AddInheritence past        H NOT in used or in done. DALDDPLa
2006-12-23 13:02:13.787 AddInheritence past        H NOT in used or in done. DDALDPLa
2006-12-23 13:02:13.954 AddInheritence past     E    NOT in used or in done. DLDDaPLA
2006-12-23 13:02:13.970 AddInheritence past     E    NOT in used or in done. DLAAaPLD
2006-12-23 13:02:43.757 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:43.758 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:43.763 VideoOutputXv Error: Child           G  was already marked as available.
2006-12-23 13:02:49.288 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:49.288 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:49.288 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:49.288 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:49.288 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:49.288 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:49.295 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:02:51.952 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:02:51.952 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:02:51.952 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:02:51.952 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:02:51.952 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:02:51.952 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:02:51.958 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:10:01.949 VideoOutputXv Error: Child         E    was already marked as available.
2006-12-23 13:10:01.977 VideoOutputXv Error: Child         E    was already marked as available.
2006-12-23 13:10:01.977 VideoOutputXv Error: Child         E    was already marked as available.
2006-12-23 13:10:01.977 VideoOutputXv Error: Child         E    was already marked as available.
2006-12-23 13:10:01.979 VideoOutputXv Error: Child         E    was already marked as available.
2006-12-23 13:19:19.685 VideoOutputXv Error: Child       C      was already marked as available.
2006-12-23 13:19:19.724 VideoOutputXv Error: Child       C      was already marked as available.
2006-12-23 13:19:19.726 VideoOutputXv Error: Child       C      was already marked as available.
2006-12-23 13:30:37.900 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:30:37.937 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:30:37.939 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:30:44.436 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:30:44.444 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:30:44.444 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:30:44.445 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:41:49.701 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:41:49.724 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:41:49.724 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:41:49.724 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:41:49.725 VideoOutputXv Error: Child        D     was already marked as available.
2006-12-23 13:51:34.470 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:34.499 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:34.502 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:41.813 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:51:41.813 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:51:41.820 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 13:51:43.347 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:43.347 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:43.347 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:43.353 VideoOutputXv Error: Child            H was already marked as available.
2006-12-23 13:51:45.205 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:45.206 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:45.206 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:45.206 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:45.206 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:45.213 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:45.213 VideoOutputXv Error: Child          F   was already marked as available.
2006-12-23 13:51:46.324 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:51:46.324 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:51:46.324 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:51:46.324 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:51:46.324 VideoOutputXv Error: Child      B       was already marked as available.
2006-12-23 13:51:46.331 VideoOutputXv Error: Child      B       was already marked as available.


I snipped when there was a ton of the same error repeated. I want to repat that NOTHING has changed in in the past few weeks, so I don't know why this is happening. My MySQL DB came back clean. I've checked google and mythtv mailing lists, but I've found no real answers (just a bunch of the same problems). Anyone have any ideas of what I can do? I tried to reinstall the NVIDIA driver, but it had no effect on my problem.

_________________
Mike
My Hardware Profile
Top
 Profile  
 
mjl
 Post subject:
PostPosted: Mon Dec 25, 2006 2:00 pm 
Offline
Joined: Sun Jun 12, 2005 10:55 pm
Posts: 3161
Location: Warwick, RI
Hi

Check your drive to be sure something hasn't filled the puppy up with logs or something ... df -h will show you what is left. R5C7 did have a flaw (patch available) that allowed one log to grow.

Mike
Top
 Profile  
 
mihanson
 Post subject:
PostPosted: Tue Dec 26, 2006 10:57 am 
Offline
Joined: Sun Sep 25, 2005 3:50 pm
Posts: 1013
Location: Los Angeles
Thanks mjl. I had that log rotation hack fixed, but that's one thing I did not check. Here's the output. It looks a-ok.

Code:
root@mythbox:/# df -h
Filesystem            Size  Used Avail Use% Mounted on
/dev/hda1             3.7G  2.3G  1.3G  66% /
/dev/vg/myth          834G  569G  266G  69% /myth% /


A few things I tried since I posted:
    Uninstalled my NVIDIA 8776 driver with:
    Code:
    sh NVIDIA<TAB> --uninstall
    and then shut my computer down. Unplugged and then booted again. Strangely, X and the frontend came right back up like normal! I know I disabled the auto-install of NVIDIA on boot. I checked XFree86.0.log and saw that it was now reporting driver 7174 (?) :?: I downloaded that drivers install file from NVIDIA and did the
    Code:
    sh NVIDIA<TAB> --uninstall
    again, but it reported no NVIDA driver was installed. I then re-installed 8776 with
    Code:
    sh NVIDIA<TAB>
    . I still had the same odd behavior from X (99%, freeze, etc.)
    I then decided to upgrade to R5D1. Things there went smooth and the only thing I did was update ivtv to 0.7.3 to fix the tinny audio on my PVR-150. However, I still have the same problem! :!:


I'm beginning to think that there is something in my DB that is causing these errors. I've done the usual check of the tables from Cesman's post. Everything was fine (no errors). I ran the optimize_mythdb.pl script and everything was fine (no errors). I'm not sure what to do at this point. If the problem is somewhere in my DB, that's like trying to find a needle in a haystack. Any one have ideas?

_________________
Mike
My Hardware Profile
Top
 Profile  
 
mihanson
 Post subject:
PostPosted: Tue Dec 26, 2006 3:59 pm 
Offline
Joined: Sun Sep 25, 2005 3:50 pm
Posts: 1013
Location: Los Angeles
Ok, I think I'm going to dump my DB and create a new one. At the same time, I'm going to keep a portion of my old DB to preserve some customizations I've done and the info on my recordings. Can someone please sanity check my thoughts? :) Here's what I plan to do:

    1) Make a back up
    Code:
     # /usr/local/bin/mythbackup

    2) Dump the DB to another file
    Code:
     $ mysqldump mythconverg -c > db_backup.sql

    3) From my db_backup.sql file, create a restore.sql file with only the tables I want to keep.
    Code:
    $ grep "INSERT INTO \`record\` "         db_backup.sql > restore.sql
    $ grep "INSERT INTO \`recorded\` "       db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`oldrecorded\` "    db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`recordedmarkup\` " db_backup.sq l >> restore.sql
    $ grep "INSERT INTO \`recordedseek\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`recordededcredits\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`recordedprogram\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`recordedrating\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`recordingprofiles\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`streams\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`channel\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`jumppoints\` "   db_backup.sql >> restore.sql
    $ grep "INSERT INTO \`keybindings\` "   db_backup.sql >> restore.sql


    4) Drop the old DB.
    Code:
    $ mysql -u root
    mysql> drop database mythconverg;
    mysql> quit


    5) Create a new mythconverg database.
    Code:
    # cd /var/lib/mysql
    # mysql < /usr/share/mythtv/sql/mc.sql


    6) Run mythtv-setup and configure as normal.

    7) Insert the saved DB info into the newly created mythconverg.
    Code:
    $ mysql -u mythtv -pmythtv mythconverg < restore.sql



Does this make sense? :?:

_________________
Mike
My Hardware Profile
Top
 Profile  
 
tjc
 Post subject:
PostPosted: Tue Dec 26, 2006 10:06 pm 
Offline
Joined: Thu Mar 25, 2004 11:00 am
Posts: 9551
Location: Arlington, MA
You may get some failures that way due to mismatches between the columns in the DB tables. The normal restore process recreates the tables to the old spec, inserts the records and then lets the DB schema upgrade code in MytTV do it's thing.

To avoid this add the appropriate "CREATE TABLE" blocks to your extract, before the corresponding inserts.
Top
 Profile  
 
mihanson
 Post subject:
PostPosted: Wed Dec 27, 2006 12:21 am 
Offline
Joined: Sun Sep 25, 2005 3:50 pm
Posts: 1013
Location: Los Angeles
As always, thanks for the tips tjc. I ended up moving my most recent backup to
Code:
mythconverg.sql.old
and then moved my
Code:
restore.sql
to
Code:
mythconverg.sql
and then did
Code:
mythrestore
That went fine, but my issue persists! :!: Since it does not seem to be the DB, I moved my "old" mythconverg back and restored it. According to my wife the problem is happening more often and faster and faster after a restart of gdm. I'm now leaning toward failing hardware. I have a NVIDIA 6200 card in my garage that I'll swap in tomorrow and see if that helps.

_________________
Mike
My Hardware Profile
Top
 Profile  
 
mihanson
 Post subject:
PostPosted: Thu Dec 28, 2006 4:56 pm 
Offline
Joined: Sun Sep 25, 2005 3:50 pm
Posts: 1013
Location: Los Angeles
OK, replacing the FX5200 with the 6200 so far seems to solve my problem. I did see one instance of
Code:
2006-12-23 12:40:07.366 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:40:07.366 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:40:07.366 VideoOutputXv Error: Child     A        was already marked as available.
2006-12-23 12:40:07.376 VideoOutputXv Error: Child     A        was already marked as available.

errors when running mythfrontend from a X Term. I'll keep an eye on it. I'll also be testing my RAM by running a memory testing program from my "Recovery Is Possible" (RIP) CD tonight. Just to be sure.

_________________
Mike
My Hardware Profile
Top
 Profile  
 

Display posts from previous:  Sort by  
Post new topic Reply to topic  Page 1 of 1
  [ 7 posts ] 


Board index Linux MythTV

All times are UTC - 6 hours




Who is online

Users browsing this forum: No registered users and 13 guests

You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot post attachments in this forum

Jump to:  
Powered by phpBB® Forum Software © phpBB Group

Theme Created By ceyhansuyu