Mythtv stalls on Live TV I've encountered this bug numerous times and sometimes it was solved erratically, by either recompiling glibc with -O3 ops, or other unknown reasons. I've also done quite a bit of searching in the past month or two. Hardware: Hauppauge WinTV PVR2 (using pvrusb2 driver http://www.isely.net/pvrusb2.html). The device streams a mpeg2 stream on /dev/v4l/video0 and is very stable. Mike also is extremely aggressive with both the development and maintaining of the pvrusb2 driver. I can easily do: $ cat /dev/v4l/video0 > test.mpg | mplayer test.mpg (test.mpg being a fifo file.) Yet, mythtv has issues with playback of this stream using the Live TV profile. I'm seeing this issue on both, my server and laptop. Both using nvidia cards. And I've recompiled mythtv with & without nvidia and opengl options including currently debug (-g). I've also recompiled libmpeg2 along with 3-4 different versions of nvidia drivers. I've also toggled all sound options within mythtv settings. I've also tried two different x11-xorg versions. This particular stall on LiveTV playback occurred within the last month to two months. Here's a log of mythfronted running within gdb. Notice, the only really useful details are a "prebuffer" error and video freezing within mythfrontend gui. The references to audio are all silenced when disabling audio playback for mythfrontend. (I can still use mythbackend for recording without problems!) $ mythfrontend -v all -l mythfrontend.log 2005-12-17 12:54:26.731 New DB connection, total: 1 Total desktop width=1024, height=768, numscreens=1 2005-12-17 12:54:26.757 Running in a window 2005-12-17 12:54:26.758 Using screen 0, 1024x768 at 0,0 2005-12-17 12:54:26.795 mythfrontend version: 0.18.1.20050510-1 www.mythtv.org 2005-12-17 12:54:26.795 Enabled verbose msgs :all 2005-12-17 12:54:28.355 max_width: 1024 max_height: 768 2005-12-17 12:54:28.480 Switching to square mode (G.A.N.T.) mythtv: could not connect to socket mythtv: No such file or directory lirc_init failed for mythtv, see preceding messages 2005-12-17 12:54:29.392 Registering Internal as a media playback plugin. 2005-12-17 12:54:29.548 Registering MythDVD DVD Media Handler as a media handler 2005-12-17 12:54:29.552 Registering MythDVD VCD Media Handler as a media handler 2005-12-17 12:54:30.193 generating cache image for: /usr/share/mythtv/themes/default/blank.png 2005-12-17 12:54:30.357 Starting media monitor. 2005-12-17 12:54:34.560 New DB connection, total: 2 2005-12-17 12:54:34.637 Connecting to backend server: 127.0.0.1:6543 (try 1 of 5) 2005-12-17 12:54:34.638 write->14 21 MYTH_PROTO_VERSION 15: 2005-12-17 12:54:34.669 Using protocol version 15 2005-12-17 12:54:34.669 write->14 37 ANN Playback localhost2.localdomain 0: 2005-12-17 12:54:34.702 write->14 29 GET_NEXT_FREE_RECORDER[]:[]-1: 2005-12-17 12:54:34.724 37 ANN Playback localhost2.localdomain 1 2005-12-17 12:54:34.726 write->16 21 MYTH_PROTO_VERSION 15: 2005-12-17 12:54:34.748 Using protocol version 15 2005-12-17 12:54:34.748 write->16 37 ANN Playback localhost2.localdomain 0: 2005-12-17 12:54:34.765 write->16 44 QUERY_RECORDER 1[]:[]SETUP_RING_BUFFE... 2005-12-17 12:54:34.782 write->17 37 ANN Playback localhost2.localdomain 0: 2005-12-17 12:54:34.830 write->18 39 ANN RingBuffer localhost2.localdomain 1: 2005-12-17 12:54:34.865 write->16 33 QUERY_RECORDER 1[]:[]SPAWN_LIVETV: 2005-12-17 12:54:38.221 write->16 37 QUERY_RECORDER 1[]:[]GET_PROGRAM_INFO: 2005-12-17 12:54:38.253 Output filters for this channel are: '' 2005-12-17 12:54:38.309 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.338 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.339 write->17 66 QUERY_RECORDER 1[]:[]SEEK_RINGBUF[]:[... 2005-12-17 12:54:38.355 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.370 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.377 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.392 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.401 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.416 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.426 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.439 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.445 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.463 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.469 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.483 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.489 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.503 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.505 AVFD 2005-12-17 12:54:38.505 AVFD: Opening Stream #0: codec id 2 2005-12-17 12:54:38.508 Using libmpeg2 for video decoding 2005-12-17 12:54:38.509 detectInterlace(Detect Scan, Detect Scan, 29.97, 480) ->Interlaced Scan 2005-12-17 12:54:38.509 Interlaced: Interlaced Scan video_height: 480 fps: 29.97 2005-12-17 12:54:38.510 AVFD: Looking for decoder for 2 2005-12-17 12:54:38.510 AVFD 2005-12-17 12:54:38.511 AVFD: Opening Stream #1: codec id 86016 2005-12-17 12:54:38.511 AVFD: Looking for decoder for 86016 2005-12-17 12:54:38.513 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.516 Stream #1 (audio track #0) is an audio stream with 2 channels. 2005-12-17 12:54:38.516 Auto-selecting audio track #1 (stream #1). 2005-12-17 12:54:38.517 It has 2 channels and we needed at least 1 2005-12-17 12:54:38.517 Initializing audio parms from audio track #0. 2005-12-17 12:54:38.531 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.532 Estimated bitrate = 6224 2005-12-17 12:54:38.532 write->16 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN: 2005-12-17 12:54:38.537 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.550 Filling position map from 0 to 97 2005-12-17 12:54:38.550 write->16 51 QUERY_RECORDER 1[]:[]FILL_POSITION_MA... 2005-12-17 12:54:38.559 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.560 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.570 Position map filled from Encoder to: 6 2005-12-17 12:54:38.571 SyncPositionMap liveTV, from Encoder: 7 entries 2005-12-17 12:54:38.571 SyncPositionMap, new totframes: 90, new length: 3, posMap size: 7 Input #0, mpeg, from 'rbuf://127.0.0.1:6543/mnt/extra2/mythtv/store//ringbuf1.nuv': Stream #0.0: Video: mpeg2video, 720x480, 29.97 fps, 6000 kb/s Stream #0.1: Audio: mp2, 48000 Hz, stereo, 224 kb/s 2005-12-17 12:54:38.572 Partial position map found 2005-12-17 12:54:38.572 Commercial Detection initialized: width = 720, height = 480, fps = 29.97, method = 255 2005-12-17 12:54:38.573 Using Sample Spacing of 6 horizontal & 4 vertical pixels. 2005-12-17 12:54:38.577 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.578 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.578 CommDetect::ClearAllMaps() 2005-12-17 12:54:38.582 Killing AudioOutputDSP 2005-12-17 12:54:38.583 Opening audio device '/dev/dsp'. 2005-12-17 12:54:38.584 Opening OSS audio device '/dev/dsp'. 2005-12-17 12:54:38.593 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.596 Audio fragment size: 4096 2005-12-17 12:54:38.597 Audio Stretch Factor: 1 2005-12-17 12:54:38.614 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.616 Ending reconfigure 2005-12-17 12:54:38.617 kickoffOutputAudioLoop: pid = 12091 2005-12-17 12:54:38.617 OutputAudioLoop: Play Event 2005-12-17 12:54:38.618 Broadcasting free space avail 2005-12-17 12:54:38.629 Broadcasting free space avail 2005-12-17 12:54:38.636 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0 2005-12-17 12:54:38.637 Broadcasting free space avail 2005-12-17 12:54:38.646 Broadcasting free space avail 2005-12-17 12:54:38.649 Using XV port 139 2005-12-17 12:54:38.653 Broadcasting free space avail X Error: BadMatch (invalid parameter attributes) 8 Major opcode: 142 Minor opcode: 14 Resource id: 0x1d6 2005-12-17 12:54:38.660 Couldn't get the color key color, and we need it. You likely won't get any video. 2005-12-17 12:54:38.661 Snapping height to avoid scaling: disphoff 480, dispyoff: 0 2005-12-17 12:54:38.661 Snapping width to avoid scaling: dispwoff 720, dispxoff: 0 2005-12-17 12:54:38.661 Broadcasting free space avail 2005-12-17 12:54:38.662 Image size. dispxoff 0, dispyoff: 0, dispwoff: 720, disphoff: 480 2005-12-17 12:54:38.662 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480 2005-12-17 12:54:38.669 Broadcasting free space avail 2005-12-17 12:54:38.677 Broadcasting free space avail 2005-12-17 12:54:38.686 Broadcasting free space avail 2005-12-17 12:54:38.693 Broadcasting free space avail 2005-12-17 12:54:38.701 Broadcasting free space avail 2005-12-17 12:54:38.709 Broadcasting free space avail 2005-12-17 12:54:38.717 Broadcasting free space avail 2005-12-17 12:54:38.726 Broadcasting free space avail 2005-12-17 12:54:38.733 Broadcasting free space avail 2005-12-17 12:54:38.741 Broadcasting free space avail 2005-12-17 12:54:38.749 Broadcasting free space avail 2005-12-17 12:54:38.757 Broadcasting free space avail 2005-12-17 12:54:38.765 Broadcasting free space avail 2005-12-17 12:54:38.773 Broadcasting free space avail 2005-12-17 12:54:38.781 Broadcasting free space avail 2005-12-17 12:54:38.789 Broadcasting free space avail 2005-12-17 12:54:38.797 Broadcasting free space avail 2005-12-17 12:54:38.806 Broadcasting free space avail 2005-12-17 12:54:38.813 Broadcasting free space avail 2005-12-17 12:54:38.821 Broadcasting free space avail 2005-12-17 12:54:38.829 Broadcasting free space avail 2005-12-17 12:54:38.837 Broadcasting free space avail 2005-12-17 12:54:38.847 Broadcasting free space avail 2005-12-17 12:54:38.853 Broadcasting free space avail 2005-12-17 12:54:38.861 Broadcasting free space avail 2005-12-17 12:54:38.869 Broadcasting free space avail 2005-12-17 12:54:38.877 Broadcasting free space avail 2005-12-17 12:54:38.886 Broadcasting free space avail 2005-12-17 12:54:38.893 Broadcasting free space avail 2005-12-17 12:54:38.901 Broadcasting free space avail 2005-12-17 12:54:38.909 Broadcasting free space avail 2005-12-17 12:54:38.917 Broadcasting free space avail 2005-12-17 12:54:38.926 Broadcasting free space avail 2005-12-17 12:54:38.930 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:38.933 Broadcasting free space avail 2005-12-17 12:54:38.953 Broadcasting free space avail 2005-12-17 12:54:38.954 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:38.961 Broadcasting free space avail 2005-12-17 12:54:38.970 Broadcasting free space avail 2005-12-17 12:54:38.977 Broadcasting free space avail 2005-12-17 12:54:38.985 Broadcasting free space avail 2005-12-17 12:54:38.993 Broadcasting free space avail 2005-12-17 12:54:39.002 Broadcasting free space avail 2005-12-17 12:54:39.009 Broadcasting free space avail 2005-12-17 12:54:39.017 Broadcasting free space avail 2005-12-17 12:54:39.026 Broadcasting free space avail 2005-12-17 12:54:39.033 Broadcasting free space avail 2005-12-17 12:54:39.041 Broadcasting free space avail 2005-12-17 12:54:39.049 Broadcasting free space avail 2005-12-17 12:54:39.057 Broadcasting free space avail 2005-12-17 12:54:39.061 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.065 Broadcasting free space avail 2005-12-17 12:54:39.074 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.075 Broadcasting free space avail 2005-12-17 12:54:39.082 write->16 33 QUERY_RECORDER 1[]:[]IS_RECORDING: 2005-12-17 12:54:39.082 OutputAudioLoop: audio paused 2005-12-17 12:54:39.083 16384 bytes free on soundcard 2005-12-17 12:54:39.159 Realtime priority would require SUID as root. 2005-12-17 12:54:39.166 _AddSamples bytes=4608, used=1, free=511999, timecode=361 2005-12-17 12:54:39.168 _AddSamples bytes=4608, used=4609, free=507391, timecode=385 2005-12-17 12:54:39.169 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.170 _AddSamples bytes=4608, used=9217, free=502783, timecode=409 2005-12-17 12:54:39.177 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.196 _AddSamples bytes=4608, used=13825, free=498175, timecode=433 2005-12-17 12:54:39.197 _AddSamples bytes=4608, used=18433, free=493567, timecode=457 2005-12-17 12:54:39.223 _AddSamples bytes=4608, used=23041, free=488959, timecode=481 2005-12-17 12:54:39.240 _AddSamples bytes=4608, used=27649, free=484351, timecode=505 2005-12-17 12:54:39.243 _AddSamples bytes=4608, used=32257, free=479743, timecode=529 2005-12-17 12:54:39.250 write->16 33 QUERY_RECORDER 1[]:[]IS_RECORDING: 2005-12-17 12:54:39.256 _AddSamples bytes=4608, used=36865, free=475135, timecode=553 2005-12-17 12:54:39.275 _AddSamples bytes=4608, used=41473, free=470527, timecode=577 2005-12-17 12:54:39.290 _AddSamples bytes=4608, used=46081, free=465919, timecode=601 2005-12-17 12:54:39.292 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.293 _AddSamples bytes=4608, used=50689, free=461311, timecode=625 2005-12-17 12:54:39.298 write->16 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE: 2005-12-17 12:54:39.301 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.312 _AddSamples bytes=4608, used=55297, free=456703, timecode=649 2005-12-17 12:54:39.318 Changing from None to WatchingLiveTV 2005-12-17 12:54:39.318 write->16 35 QUERY_RECORDER 1[]:[]GET_INPUT_NAME: 2005-12-17 12:54:39.333 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.336 _AddSamples bytes=4608, used=59905, free=452095, timecode=673 2005-12-17 12:54:39.338 _AddSamples bytes=4608, used=64513, free=447487, timecode=697 2005-12-17 12:54:39.341 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.356 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.358 _AddSamples bytes=4608, used=69121, free=442879, timecode=721 2005-12-17 12:54:39.365 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.377 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver? 2005-12-17 12:54:39.378 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory 2005-12-17 12:54:39.383 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.385 _AddSamples bytes=4608, used=73729, free=438271, timecode=745 2005-12-17 12:54:39.387 OpenGLVideoSync: GLX extensions: GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control GLX_ARB_multisample GLX_ARB_get_proc_address 2005-12-17 12:54:39.389 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.418 _AddSamples bytes=4608, used=78337, free=433663, timecode=769 2005-12-17 12:54:39.420 _AddSamples bytes=4608, used=82945, free=429055, timecode=793 2005-12-17 12:54:39.421 OutputAudioLoop: Play Event 2005-12-17 12:54:39.421 Broadcasting free space avail 2005-12-17 12:54:39.422 Broadcasting free space avail 2005-12-17 12:54:39.423 Broadcasting free space avail 2005-12-17 12:54:39.423 Broadcasting free space avail 2005-12-17 12:54:39.423 audio waiting for space on soundcard: have 184 need 4096 2005-12-17 12:54:39.433 audio waiting for space on soundcard: have 2116 need 4096 2005-12-17 12:54:39.445 Broadcasting free space avail 2005-12-17 12:54:39.446 audio waiting for space on soundcard: have 480 need 4096 2005-12-17 12:54:39.449 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.452 _AddSamples bytes=4608, used=67073, free=444927, timecode=817 2005-12-17 12:54:39.457 audio waiting for space on soundcard: have 2628 need 4096 2005-12-17 12:54:39.462 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.469 Broadcasting free space avail 2005-12-17 12:54:39.478 audio waiting for space on soundcard: have 2460 need 4096 2005-12-17 12:54:39.483 _AddSamples bytes=4608, used=67585, free=444415, timecode=841 2005-12-17 12:54:39.484 _AddSamples bytes=4608, used=72193, free=439807, timecode=865 2005-12-17 12:54:39.489 Broadcasting free space avail 2005-12-17 12:54:39.497 audio waiting for space on soundcard: have 2180 need 4096 2005-12-17 12:54:39.510 Broadcasting free space avail 2005-12-17 12:54:39.510 audio waiting for space on soundcard: have 548 need 4096 2005-12-17 12:54:39.516 _AddSamples bytes=4608, used=68609, free=443391, timecode=889 2005-12-17 12:54:39.521 audio waiting for space on soundcard: have 2632 need 4096 2005-12-17 12:54:39.533 Broadcasting free space avail 2005-12-17 12:54:39.534 audio waiting for space on soundcard: have 980 need 4096 2005-12-17 12:54:39.538 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.539 _AddSamples bytes=4608, used=69121, free=442879, timecode=913 2005-12-17 12:54:39.545 audio waiting for space on soundcard: have 3140 need 4096 2005-12-17 12:54:39.546 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.557 Broadcasting free space avail 2005-12-17 12:54:39.558 audio waiting for space on soundcard: have 1544 need 4096 2005-12-17 12:54:39.566 _AddSamples bytes=4608, used=69633, free=442367, timecode=937 2005-12-17 12:54:39.568 _AddSamples bytes=4608, used=74241, free=437759, timecode=961 2005-12-17 12:54:39.569 audio waiting for space on soundcard: have 3652 need 4096 2005-12-17 12:54:39.581 Broadcasting free space avail 2005-12-17 12:54:39.582 audio waiting for space on soundcard: have 2104 need 4096 2005-12-17 12:54:39.593 Broadcasting free space avail 2005-12-17 12:54:39.594 audio waiting for space on soundcard: have 216 need 4096 2005-12-17 12:54:39.596 _AddSamples bytes=4608, used=70657, free=441343, timecode=985 2005-12-17 12:54:39.605 audio waiting for space on soundcard: have 2416 need 4096 2005-12-17 12:54:39.617 Broadcasting free space avail 2005-12-17 12:54:39.618 audio waiting for space on soundcard: have 724 need 4096 2005-12-17 12:54:39.629 audio waiting for space on soundcard: have 2900 need 4096 2005-12-17 12:54:39.639 _AddSamples bytes=4608, used=71169, free=440831, timecode=1009 2005-12-17 12:54:39.844 _AddSamples bytes=4608, used=75777, free=436223, timecode=1033 2005-12-17 12:54:39.849 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.853 Broadcasting free space avail 2005-12-17 12:54:39.854 Broadcasting free space avail 2005-12-17 12:54:39.855 Broadcasting free space avail 2005-12-17 12:54:39.855 Broadcasting free space avail 2005-12-17 12:54:39.855 audio waiting for space on soundcard: have 176 need 4096 2005-12-17 12:54:39.857 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.865 audio waiting for space on soundcard: have 2116 need 4096 2005-12-17 12:54:39.877 Broadcasting free space avail 2005-12-17 12:54:39.878 audio waiting for space on soundcard: have 436 need 4096 2005-12-17 12:54:39.881 _AddSamples bytes=4608, used=59905, free=452095, timecode=1057 2005-12-17 12:54:39.889 audio waiting for space on soundcard: have 2660 need 4096 2005-12-17 12:54:39.901 Broadcasting free space avail 2005-12-17 12:54:39.902 audio waiting for space on soundcard: have 944 need 4096 2005-12-17 12:54:39.907 _AddSamples bytes=4608, used=60417, free=451583, timecode=1081 2005-12-17 12:54:39.913 audio waiting for space on soundcard: have 3164 need 4096 2005-12-17 12:54:39.925 Broadcasting free space avail 2005-12-17 12:54:39.926 audio waiting for space on soundcard: have 1464 need 4096 2005-12-17 12:54:39.936 _AddSamples bytes=4608, used=60929, free=451071, timecode=1105 2005-12-17 12:54:39.937 audio waiting for space on soundcard: have 3692 need 4096 2005-12-17 12:54:39.938 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:39.939 _AddSamples bytes=4608, used=65537, free=446463, timecode=1129 2005-12-17 12:54:39.945 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:39.949 Broadcasting free space avail 2005-12-17 12:54:39.950 audio waiting for space on soundcard: have 1992 need 4096 2005-12-17 12:54:39.961 Broadcasting free space avail 2005-12-17 12:54:39.962 audio waiting for space on soundcard: have 176 need 4096 2005-12-17 12:54:39.973 audio waiting for space on soundcard: have 2376 need 4096 2005-12-17 12:54:39.976 _AddSamples bytes=4608, used=61953, free=450047, timecode=1153 2005-12-17 12:54:39.986 Broadcasting free space avail 2005-12-17 12:54:39.986 audio waiting for space on soundcard: have 768 need 4096 2005-12-17 12:54:39.997 audio waiting for space on soundcard: have 2888 need 4096 2005-12-17 12:54:40.004 _AddSamples bytes=4608, used=62465, free=449535, timecode=1177 2005-12-17 12:54:40.009 Broadcasting free space avail 2005-12-17 12:54:40.010 audio waiting for space on soundcard: have 1224 need 4096 2005-12-17 12:54:40.021 audio waiting for space on soundcard: have 3404 need 4096 2005-12-17 12:54:40.026 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:40.034 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:40.034 Broadcasting free space avail 2005-12-17 12:54:40.034 audio waiting for space on soundcard: have 1852 need 4096 2005-12-17 12:54:40.038 _AddSamples bytes=4608, used=58881, free=453119, timecode=1201 2005-12-17 12:54:40.060 Broadcasting free space avail 2005-12-17 12:54:40.061 audio waiting for space on soundcard: have 2784 need 4096 2005-12-17 12:54:40.062 _AddSamples bytes=4608, used=59393, free=452607, timecode=1225 2005-12-17 12:54:40.073 Broadcasting free space avail 2005-12-17 12:54:40.074 audio waiting for space on soundcard: have 1280 need 4096 2005-12-17 12:54:40.085 audio waiting for space on soundcard: have 3400 need 4096 2005-12-17 12:54:40.095 _AddSamples bytes=4608, used=59905, free=452095, timecode=1249 2005-12-17 12:54:40.097 Broadcasting free space avail 2005-12-17 12:54:40.098 audio waiting for space on soundcard: have 1688 need 4096 2005-12-17 12:54:40.098 Using audio as timebase 2005-12-17 12:54:40.099 Video timing method: SGI OpenGL 2005-12-17 12:54:40.099 Refresh rate: 11765, frame interval: 33366 2005-12-17 12:54:40.109 audio waiting for space on soundcard: have 3924 need 4096 2005-12-17 12:54:40.110 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:40.118 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:40.121 Broadcasting free space avail 2005-12-17 12:54:40.122 audio waiting for space on soundcard: have 2256 need 4096 2005-12-17 12:54:40.123 _AddSamples bytes=4608, used=56321, free=455679, timecode=1273 2005-12-17 12:54:40.128 _AddSamples bytes=4608, used=60929, free=451071, timecode=1297 2005-12-17 12:54:40.133 Broadcasting free space avail 2005-12-17 12:54:40.134 audio waiting for space on soundcard: have 460 need 4096 2005-12-17 12:54:40.145 audio waiting for space on soundcard: have 2628 need 4096 2005-12-17 12:54:40.158 Broadcasting free space avail 2005-12-17 12:54:40.158 audio waiting for space on soundcard: have 1092 need 4096 2005-12-17 12:54:40.161 _AddSamples bytes=4608, used=57345, free=454655, timecode=1321 2005-12-17 12:54:40.169 audio waiting for space on soundcard: have 3156 need 4096 2005-12-17 12:54:40.201 Broadcasting free space avail 2005-12-17 12:54:40.202 Broadcasting free space avail 2005-12-17 12:54:40.202 audio waiting for space on soundcard: have 1252 need 4096 2005-12-17 12:54:40.203 A/V diverged by -4.9002 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.203 A/V diverged by -8.32809 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.203 A/V diverged by -10.6517 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.204 A/V diverged by -12.1472 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.204 A/V diverged by -13.0216 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.205 A/V diverged by -13.4301 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.205 A/V diverged by -13.4892 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.206 A/V diverged by -13.2863 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.206 A/V diverged by -12.8943 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.206 A/V diverged by -12.3531 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.207 A/V diverged by -11.6924 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.207 A/V diverged by -10.9497 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.207 A/V diverged by -10.1454 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.208 A/V diverged by -9.29485 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.208 A/V diverged by -8.40973 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.208 A/V diverged by -7.49862 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.209 A/V diverged by -6.56803 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.209 A/V diverged by -5.62282 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.209 A/V diverged by -4.66667 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.210 A/V diverged by -3.7023 frames, dropping frame to keep audio in sync 2005-12-17 12:54:40.219 Broadcasting free space avail 2005-12-17 12:54:40.219 audio waiting for space on soundcard: have 444 need 4096 2005-12-17 12:54:40.229 audio waiting for space on soundcard: have 2392 need 4096 2005-12-17 12:54:40.230 _AddSamples bytes=4608, used=49665, free=462335, timecode=1345 2005-12-17 12:54:40.237 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:40.239 HandleGopStart: gopset not set, syncing positionMap 2005-12-17 12:54:40.246 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:40.249 Broadcasting free space avail 2005-12-17 12:54:40.250 audio waiting for space on soundcard: have 2212 need 4096 2005-12-17 12:54:40.261 Broadcasting free space avail 2005-12-17 12:54:40.262 audio waiting for space on soundcard: have 420 need 4096 2005-12-17 12:54:40.274 audio waiting for space on soundcard: have 2704 need 4096 2005-12-17 12:54:40.286 Broadcasting free space avail 2005-12-17 12:54:40.286 audio waiting for space on soundcard: have 1076 need 4096 2005-12-17 12:54:40.297 audio waiting for space on soundcard: have 3140 need 4096 2005-12-17 12:54:40.309 Broadcasting free space avail 2005-12-17 12:54:40.310 audio waiting for space on soundcard: have 1448 need 4096 2005-12-17 12:54:40.321 audio waiting for space on soundcard: have 3680 need 4096 2005-12-17 12:54:40.333 Broadcasting free space avail 2005-12-17 12:54:40.334 audio waiting for space on soundcard: have 2004 need 4096 2005-12-17 12:54:40.337 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:40.345 Broadcasting free space avail 2005-12-17 12:54:40.346 write->17 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI... 2005-12-17 12:54:40.346 audio waiting for space on soundcard: have 176 need 4096 2005-12-17 12:54:40.361 audio waiting for space on soundcard: have 3016 need 4096 2005-12-17 12:54:40.369 Read(): reqd=128000, rcvd=128000, rept=128000, error=0 2005-12-17 12:54:40.373 Broadcasting free space avail 2005-12-17 12:54:40.374 audio waiting for space on soundcard: have 1464 need 4096 2005-12-17 12:54:40.385 audio waiting for space on soundcard: have 3652 need 4096 2005-12-17 12:54:40.397 Broadcasting free space avail 2005-12-17 12:54:40.398 audio waiting for space on soundcard: have 1948 need 4096 2005-12-17 12:54:40.409 Broadcasting free space avail 2005-12-17 12:54:40.410 audio waiting for space on soundcard: have 236 need 4096 2005-12-17 12:54:40.421 audio waiting for space on soundcard: have 2372 need 4096 2005-12-17 12:54:40.433 Broadcasting free space avail 2005-12-17 12:54:40.434 audio waiting for space on soundcard: have 692 need 4096 2005-12-17 12:54:40.445 audio waiting for space on soundcard: have 2888 need 4096 2005-12-17 12:54:40.454 prebuffering pause 2005-12-17 12:54:40.454 waiting for prebuffer... 2005-12-17 12:54:40.457 OutputAudioLoop: audio paused 2005-12-17 12:54:40.458 5264 bytes free on soundcard 2005-12-17 12:54:40.465 6720 bytes free on soundcard 2005-12-17 12:54:40.477 9032 bytes free on soundcard 2005-12-17 12:54:40.486 10744 bytes free on soundcard 2005-12-17 12:54:40.493 12108 bytes free on soundcard 2005-12-17 12:54:40.506 14668 bytes free on soundcard 2005-12-17 12:54:40.514 16144 bytes free on soundcard 2005-12-17 12:54:40.521 16384 bytes free on soundcard 2005-12-17 12:54:40.598 prebuffer wait timed out.. 2005-12-17 12:54:40.599 waiting for prebuffer... 2005-12-17 12:54:40.737 prebuffer wait timed out.. 2005-12-17 12:54:40.741 waiting for prebuffer... 2005-12-17 12:54:40.881 prebuffer wait timed out.. 2005-12-17 12:54:40.910 waiting for prebuffer... 2005-12-17 12:54:41.049 prebuffer wait timed out.. 2005-12-17 12:54:41.058 waiting for prebuffer... 2005-12-17 12:54:41.197 prebuffer wait timed out.. 2005-12-17 12:54:41.199 waiting for prebuffer... 2005-12-17 12:54:41.337 prebuffer wait timed out.. 2005-12-17 12:54:41.341 waiting for prebuffer... 2005-12-17 12:54:41.481 prebuffer wait timed out.. 2005-12-17 12:54:41.505 waiting for prebuffer... 2005-12-17 12:54:41.645 prebuffer wait timed out.. 2005-12-17 12:54:41.658 waiting for prebuffer... 2005-12-17 12:54:41.797 prebuffer wait timed out.. 2005-12-17 12:54:41.799 waiting for prebuffer... 2005-12-17 12:54:41.937 prebuffer wait timed out.. ... clip ... 2005-12-17 12:54:59.335 ReadStringList timeout (quick). Remote encoder not responding. 2005-12-17 12:54:59.336 WriteStringList: Bad socket 2005-12-17 12:54:59.336 ReadStringList: Bad socket Remote encoder not responding. 2005-12-17 12:54:59.337 WriteStringList: Bad socket 2005-12-17 12:54:59.337 ReadStringList: Bad socket Remote encoder not responding. ASSERT: "i <= nodes" in /usr/qt/3/include/qvaluelist.h (373) 2005-12-17 12:54:59.338 PosMapFromEnc: Warning, tried to fetch PositionMap from Encoder but encoder returned framesWritten == 0 2005-12-17 12:54:59.339 SyncPositionMap liveTV, from Encoder: 7 entries 2005-12-17 12:54:59.339 Stream initial keyframedist: 15. 2005-12-17 12:54:59.351 WriteStringList: Bad socket 2005-12-17 12:54:59.352 ReadStringList: Bad socket Remote encoder not responding. 2005-12-17 12:54:59.371 _AddSamples bytes=4608, used=13313, free=498687, timecode=1369 2005-12-17 12:54:59.373 _AddSamples bytes=4608, used=17921, free=494079, timecode=1393 2005-12-17 12:54:59.375 prebuffer wait timed out.. X Error: BadMatch (invalid parameter attributes) 8 Major opcode: 142 Minor opcode: 14 Resource id: 0x1d6 2005-12-17 12:54:59.379 Couldn't get the color key color, and we need it. You likely won't get any video. 2005-12-17 12:54:59.517 Killing AudioOutputDSP 2005-12-17 12:54:59.527 OutputAudioLoop: Stop Event 2005-12-17 12:54:59.527 kickoffOutputAudioLoop exiting 2005-12-17 12:54:59.598 write->17 33 QUERY_RECORDER 1[]:[]DONE_RINGBUF: 2005-12-17 12:54:59.626 Changing from WatchingLiveTV to None 2005-12-17 12:54:59.635 Changing from None to None
Sorry. I should have attached this log as an attachment!!! Let's hope this puppy closes out soon without too many posts.
emerge --info?
Created attachment 75257 [details] emerge --info CFLAGS & USE flags have been removed and added during multiple rebuilds of mythtv which also included restarting mythbackend. Also ensured the mythbackend was rstarted by checking ps -ax |grep myth.
Found the solution! (Think this is one of the first times this solution has been posted for this particular problem! Here's the solution... ie. mysql database error?) Found it! After looking over logs extensively of mythfrontend & mythbackend (with --verbose all and using gdb & strace). All provided errors such as buffer errors and video errors were entirely unuseful in detecting this bug. I went into mythsetup and chose, "Capture Cards" > "(/dev/v4l/video0)" device" > default input = "television". I barely noticed but previously "default input" was set to a value of "Television" and not "television"! All other channels were started with a lower case letter (ie. s-video, composite, and radio). So I changed the value to "television" and Live TV finally worked! When I returned to the same menu applet within mythsetup, "Television" default input setting no longer existed. All were lower case letters. (wow. ~2 mos of recompiling and other things and it was this!)
Strangest thing I've ever heard of.... Not to good to get those issues and have it suddently fixed. I'd clean up CFLAGS and memtest your hardware.
I just posted the solution to my issue here on "2005-12-21 17:18" (You posted right after it!) It was the "input source" being incorrectly defined within mythsetup. This appears to be a mythtv bug and not a bug originating here.