[mythtv-users] Commonly seen bug with undocumented solution!

roger at eskimo.com roger at eskimo.com
Sat Dec 17 16:42:03 EST 2005


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 and
seem to have found that this bug is caused by an audio issue while using
the
Live TV playback.

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.

(Please, when replying, omit any unneeded text such as most of the
details listed below!) 

As I'm guessing, running into an issue with sound processing through a
buffer causing video to hang after < 1 second of playback.  On my
laptop, Live TV will stutter erratically.  I'm using a SBLive Value card
using the kernel emu10k1 driver.  No other issues with any other sound
or video playing apps on my system. The pvrusb2 kernel logs show
streaming is just fine & dandy and waiting for mythtv to stream it.

I've seen this bug posted several times without any solution using
google.

# aplay -l
**** List of PLAYBACK Hardware Devices ****
card 0: Live [SBLive! Value [CT4670]], device 0: emu10k1 [ADC
Capture/Standard PCM Playback]
  Subdevices: 32/32
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
  ...
  Subdevice #31: subdevice #31
card 0: Live [SBLive! Value [CT4670]], device 2: emu10k1 efx
[Multichannel Capture/PT Playback]
  Subdevices: 8/8
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
  Subdevice #4: subdevice #4
  Subdevice #5: subdevice #5
  Subdevice #6: subdevice #6
  Subdevice #7: subdevice #7
card 0: Live [SBLive! Value [CT4670]], device 3: emu10k1 [Multichannel
Playback]
  Subdevices: 1/1
  Subdevice #0: subdevice #0

$ 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

--
Roger
http://www.eskimo.com/~roger/index.html
Key fingerprint = 8977 A252 2623 F567 70CD 1261 640F C963 1005 1D61

Sat Dec 17 13:34:31 PST 2005



More information about the mythtv-users mailing list