2006-09-16 03:39:40.191 Using runtime prefix = /usr/local 2006-09-16 03:39:40.225 DPMS is active. 2006-09-16 03:39:40.285 New DB connection, total: 1 2006-09-16 03:39:40.314 Connected to database 'mythconverg' at host: localhost 2006-09-16 03:39:40.320 Total desktop dim: 720x576, with 1 screen[s]. 2006-09-16 03:39:40.330 Using screen 0, 720x576 at 0,0 2006-09-16 03:39:40.588 user: 1000 effective user: 1000 before privileged thread 2006-09-16 03:39:40.590 user: 1000 effective user: 1000 after privileged thread 2006-09-16 03:39:40.594 Current Schema Version: 1160 2006-09-16 03:39:40.596 mythfrontend version: 0.20.20060828-3 www.mythtv.org 2006-09-16 03:39:40.596 Enabled verbose msgs: all nodatabase 2006-09-16 03:39:40.622 user: 1000 effective user: 1000 run_priv_thread 2006-09-16 03:39:41.324 max_width: 720 max_height: 576 2006-09-16 03:39:41.692 Total desktop dim: 720x576, with 1 screen[s]. 2006-09-16 03:39:41.695 Using screen 0, 720x576 at 0,0 2006-09-16 03:39:41.697 Switching to square mode (Titivillus) __driCreateNewScreen_20050727 - succeeded 2006-09-16 03:39:41.839 Using the Qt painter 2006-09-16 03:39:42.932 New DB connection, total: 2 2006-09-16 03:39:42.934 Connected to database 'mythconverg' at host: localhost 2006-09-16 03:39:42.937 /home/mythtv/.mythtv/joystickmenurc not found. 2006-09-16 03:39:42.937 Joystick disabled. 2006-09-16 03:39:43.260 Loading from: /usr/local/share/mythtv/themes/default/base.xml 2006-09-16 03:39:44.991 Registering Internal as a media playback plugin. 2006-09-16 03:39:45.122 Registering MythDVD DVD Media Handler as a media handler ext() 2006-09-16 03:39:45.126 Registering MythDVD VCD Media Handler as a media handler ext() 2006-09-16 03:39:45.521 generating cache image for: /usr/local/share/mythtv/themes/default/blank.png 2006-09-16 03:39:58.016 MythSocket(84bd718:11): new socket 2006-09-16 03:39:58.026 Connecting to backend server: 192.168.178.31:6543 (try 1 of 5) 2006-09-16 03:39:58.027 MythSocket(8540c48:12): new socket 2006-09-16 03:39:58.028 MythSocket(8540c48:12): attempting connect() to (192.168.178.31:6543) 2006-09-16 03:39:58.029 MythSocket(8540c48:12): state change Idle -> Connected 2006-09-16 03:39:58.030 write -> 12 21 MYTH_PROTO_VERSION 30 2006-09-16 03:39:58.033 read <- 12 13 ACCEPT[]:[]30 2006-09-16 03:39:58.033 Using protocol version 30 2006-09-16 03:39:58.034 write -> 12 17 ANN Monitor pvr 0 2006-09-16 03:39:58.036 read <- 12 2 OK 2006-09-16 03:39:58.036 MythSocket(84bd718:11): attempting connect() to (192.168.178.31:6543) 2006-09-16 03:39:58.037 MythSocket(84bd718:11): state change Idle -> Connected 2006-09-16 03:39:58.037 write -> 11 17 ANN Monitor pvr 1 2006-09-16 03:39:58.041 read <- 11 2 OK 2006-09-16 03:39:58.042 MythSocket(84bd718:11): UpRef: 1 2006-09-16 03:39:58.042 write -> 12 29 GET_NEXT_FREE_RECORDER[]:[]-1 2006-09-16 03:39:58.043 MythSocket: readyread thread start 2006-09-16 03:39:58.045 read <- 12 29 1[]:[]192.168.178.31[]:[]6543 2006-09-16 03:39:58.053 TV: Attempting to change from None to WatchingLiveTV 2006-09-16 03:39:58.054 MythSocket(85375c8:15): new socket 2006-09-16 03:39:58.055 MythSocket(85375c8:15): attempting connect() to (192.168.178.31:6543) 2006-09-16 03:39:58.055 MythSocket(85375c8:15): state change Idle -> Connected 2006-09-16 03:39:58.056 write -> 15 21 MYTH_PROTO_VERSION 30 2006-09-16 03:39:58.058 read <- 15 13 ACCEPT[]:[]30 2006-09-16 03:39:58.059 Using protocol version 30 2006-09-16 03:39:58.059 write -> 15 18 ANN Playback pvr 0 2006-09-16 03:39:58.062 read <- 15 2 OK 2006-09-16 03:39:58.063 write -> 15 72 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-pvr-2006-09-16T03:39:5... 2006-09-16 03:39:58.609 MythSocket(84bd718:11): socket is readable 2006-09-16 03:39:58.610 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:39:58.610 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:39:58.637 MythSocket(84bd718:11): socket is readable 2006-09-16 03:39:58.638 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:39:58.638 read <- 11 78 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr-2006-09-16T03:39... 2006-09-16 03:39:58.674 read <- 15 2 ok 2006-09-16 03:39:58.678 LiveTVChain(live-pvr-2006-09-16T03:39:58): ReloadAll(): Added new recording 2006-09-16 03:39:58.690 RingBuf(/mnt/recordings/7_20060916033958.mpg): OpenFile(/mnt/recordings/7_20060916033958.mpg, 12) 2006-09-16 03:39:59.376 RingBuf(/mnt/recordings/7_20060916033958.mpg): CalcReadAheadThresh(4000 KB) -> threshhold(146 KB) min read(32 KB) blk size(64 KB) 2006-09-16 03:39:59.377 write -> 15 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2006-09-16 03:39:59.378 read <- 15 1 1 2006-09-16 03:39:59.379 write -> 15 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2006-09-16 03:39:59.380 read <- 15 1 1 2006-09-16 03:39:59.380 TV: StartRecorder(): took 3 ms to start recorder. 2006-09-16 03:39:59.380 write -> 15 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE 2006-09-16 03:39:59.388 DPMS Deactivated 2006-09-16 03:39:59.392 read <- 15 2 25 2006-09-16 03:39:59.443 write -> 12 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2006-09-16 03:39:59.445 read <- 12 2 OK 2006-09-16 03:39:59.469 MythSocket(84bd718:11): socket is readable 2006-09-16 03:39:59.469 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:39:59.470 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:39:59.530 TV: Output filters for this channel are: '' 2006-09-16 03:39:59.625 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 6000000 at 0x0x84ee1e0 2006-09-16 03:39:59.639 VideoOutputXv: XvMC version: 1.1 2006-09-16 03:39:59.640 XvMCSurfaceTypes::find(w 704, h 496, chroma 1, vld 1, idct 0, mpeg2, sub-width 0, sub-height 0, disp, p<= 68, 680 <=p, port, surfNum) 2006-09-16 03:39:59.641 Trying XvMC port 68 2006-09-16 03:39:59.643 Found a suitable XvMC surface 0 2006-09-16 03:39:59.645 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask 2006-09-16 03:39:59.646 VideoOutputXv: Adaptor#0: XV_SWOV has flag[s]: XvInputMask XvImageMask 2006-09-16 03:39:59.646 XvMCSurfaceTypes::find(w 704, h 496, chroma 1, vld 1, idct 0, mpeg2, sub-width 0, sub-height 0, disp, p<= 68, 680 <=p, port, surfNum) 2006-09-16 03:39:59.647 Trying XvMC port 68 2006-09-16 03:39:59.647 Found a suitable XvMC surface 0 2006-09-16 03:39:59.648 VideoOutputXv: Grabbed xv port 68 2006-09-16 03:39:59.648 VideoOutputXv: XvMC surface found with VLD support on port 68 2006-09-16 03:39:59.656 VideoOutputXv: Closing XVideo port 68 2006-09-16 03:39:59.658 detectInterlace(Detect Scan, Interlaced Scan, 25, 496) ->Interlaced Scan 2006-09-16 03:39:59.658 AFD: Looking for decoder for MPEG2VIDEO_XVMC_VLD 2006-09-16 03:39:59.659 AFD: Opened codec 0x8e919f0, id(MPEG2VIDEO_XVMC_VLD) type(Video) 2006-09-16 03:39:59.659 AFD: Stream #1, has id 0x448 codec id MP2, type Audio, bitrate 224000 at 0x0x8e91d40 2006-09-16 03:39:59.659 AFD: Looking for decoder for MP2 2006-09-16 03:39:59.826 AFD: Opened codec 0x8e91df0, id(MP2) type(Audio) 2006-09-16 03:39:59.826 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Unknown language(0). 2006-09-16 03:39:59.827 RingBuf(/mnt/recordings/7_20060916033958.mpg): CalcReadAheadThresh(6224 KB) -> threshhold(227 KB) min read(32 KB) blk size(128 KB) 2006-09-16 03:39:59.828 AFD: Selected track 1: Unknown MP2 2ch (A/V Stream #1) 2006-09-16 03:39:59.828 AFD: Initializing audio parms from audio track #1 2006-09-16 03:39:59.833 AFD: Audio format changed from id(NONE) -1Hz -1ch 0bps ; id(NONE) -1Hz -1ch 0bps to id( MP2) 48000Hz 2ch 16bps ; id( MP2) 48000Hz 2ch 16bps 2006-09-16 03:39:59.851 AO: Killing AudioOutputDSP 2006-09-16 03:39:59.852 Opening OSS audio device '/dev/dsp'. 2006-09-16 03:39:59.866 AO: Audio fragment size: 4096 2006-09-16 03:39:59.870 AO: Audio Stretch Factor: 1 2006-09-16 03:39:59.871 AO: Ending reconfigure 2006-09-16 03:39:59.871 Dec: Trying to select track (w/lang) 2006-09-16 03:39:59.871 Dec: Selecting first track 2006-09-16 03:39:59.873 Dec: Selected track #1 in the Unknown language(0) 2006-09-16 03:39:59.873 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0) 2006-09-16 03:39:59.880 Position map filled from DB to: 1 2006-09-16 03:39:59.881 SyncPositionMap watchingrecording, from DB: 1 entries 2006-09-16 03:39:59.881 write -> 15 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN 2006-09-16 03:39:59.883 read <- 15 8 0[]:[]12 2006-09-16 03:39:59.883 Filling position map from 2 to 1 2006-09-16 03:39:59.884 write -> 15 50 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]2[]:[]1 2006-09-16 03:39:59.885 read <- 15 2 ok 2006-09-16 03:39:59.885 Position map filled from Encoder to: 1 2006-09-16 03:39:59.885 SyncPositionMap watchingrecording total: 1 entries 2006-09-16 03:39:59.886 SyncPositionMap, new totframes: 12, new length: 0, posMap size: 1 Input #0, mpeg, from '/mnt/recordings/7_20060916033958.mpg': Duration: N/A, bitrate: N/A Stream #0.0[0x1e0], 1/90000: Video: mpegvideo_xvmc_vld, yuv420p, 704x496, 1/25, 6000 kb/s, 25.00 fps(r) Stream #0.1[0x1c0], 1/90000: Audio: mp2, 48000 Hz, stereo, 224 kb/s 2006-09-16 03:39:59.887 AFD: Partial position map found 2006-09-16 03:39:59.887 AFD: Successfully opened decoder for file: "/mnt/recordings/7_20060916033958.mpg". novideo(0) 2006-09-16 03:39:59.899 AO: kickoffOutputAudioLoop: pid = 8850 2006-09-16 03:39:59.900 AO: OutputAudioLoop: Play Event 2006-09-16 03:39:59.900 AO: Broadcasting free space avail 2006-09-16 03:39:59.978 VideoOutputXv: ctor 2006-09-16 03:39:59.981 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0 2006-09-16 03:39:59.982 Snapping width to avoid scaling: width: 704, left: 8 2006-09-16 03:39:59.982 Display Rect left: 8, top: 0, width: 704, height: 576, aspect: 1.33333 2006-09-16 03:39:59.982 Video Rect left: 0, top: 0, width: 704, height: 496, aspect: 1.33333 2006-09-16 03:39:59.983 VideoOutputXv: Pixel dimensions: Screen 720x576, window 720x576 2006-09-16 03:39:59.983 VideoOutputXv: Estimated display dimensions: 244x195 mm Aspect: 1.25128 2006-09-16 03:39:59.985 VideoOutputXv: Estimated window dimensions: 244x195 mm Aspect: 1.25128 2006-09-16 03:39:59.988 VideoOutputXv: XvMCTex: Init failed 2006-09-16 03:39:59.988 VideoOutputXv: Number of bits per pixel is 16, but we only support ARGB 32 bbp for ChromaKeyOSD. 2006-09-16 03:39:59.989 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask 2006-09-16 03:39:59.990 VideoOutputXv: Adaptor#0: XV_SWOV has flag[s]: XvInputMask XvImageMask 2006-09-16 03:39:59.994 XvMCSurfaceTypes::find(w 704, h 496, chroma 1, vld 1, idct 0, mpeg2, sub-width 0, sub-height 0, disp, p<= 68, 680 <=p, port, surfNum) 2006-09-16 03:39:59.995 Trying XvMC port 68 2006-09-16 03:39:59.996 Found a suitable XvMC surface 0 2006-09-16 03:39:59.996 VideoOutputXv: Grabbed xv port 68 2006-09-16 03:39:59.997 VideoOutputXv: XvMC surface found with VLD support on port 68 2006-09-16 03:39:59.997 VideoOutputXv: XvMC Adaptor Name: 'XV_SWOV' 2006-09-16 03:40:00.017 Snapping width to avoid scaling: width: 704, left: 8 2006-09-16 03:40:00.017 Display Rect left: 8, top: 0, width: 704, height: 576, aspect: 1.25128 2006-09-16 03:40:00.018 Video Rect left: 0, top: 0, width: 704, height: 496, aspect: 1.33333 2006-09-16 03:40:00.052 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:00.052 AO: 65536 bytes free on soundcard 2006-09-16 03:40:00.061 AO: 63072 bytes free on soundcard 2006-09-16 03:40:00.068 AO: 60320 bytes free on soundcard 2006-09-16 03:40:00.077 AO: 62048 bytes free on soundcard 2006-09-16 03:40:00.084 AO: 59264 bytes free on soundcard 2006-09-16 03:40:00.094 AO: 61216 bytes free on soundcard 2006-09-16 03:40:00.100 AO: 62336 bytes free on soundcard 2006-09-16 03:40:00.109 AO: 60000 bytes free on soundcard 2006-09-16 03:40:00.116 AO: 61280 bytes free on soundcard 2006-09-16 03:40:00.125 AO: 63136 bytes free on soundcard 2006-09-16 03:40:00.132 AO: 60256 bytes free on soundcard 2006-09-16 03:40:00.141 AO: 62080 bytes free on soundcard 2006-09-16 03:40:00.148 AO: 59296 bytes free on soundcard 2006-09-16 03:40:00.160 AO: 61600 bytes free on soundcard 2006-09-16 03:40:00.167 AO: 58976 bytes free on soundcard 2006-09-16 03:40:00.178 AO: 60928 bytes free on soundcard 2006-09-16 03:40:00.185 AO: 62368 bytes free on soundcard 2006-09-16 03:40:00.194 AO: 60032 bytes free on soundcard 2006-09-16 03:40:00.200 AO: 61056 bytes free on soundcard 2006-09-16 03:40:00.210 AO: 63104 bytes free on soundcard 2006-09-16 03:40:00.216 AO: 60032 bytes free on soundcard 2006-09-16 03:40:00.226 AO: 62048 bytes free on soundcard 2006-09-16 03:40:00.232 AO: 59008 bytes free on soundcard 2006-09-16 03:40:00.240 AO: 60544 bytes free on soundcard 2006-09-16 03:40:00.248 AO: 62048 bytes free on soundcard 2006-09-16 03:40:00.256 AO: 59552 bytes free on soundcard 2006-09-16 03:40:00.264 AO: 61088 bytes free on soundcard 2006-09-16 03:40:00.272 AO: 62624 bytes free on soundcard 2006-09-16 03:40:00.280 AO: 60032 bytes free on soundcard 2006-09-16 03:40:00.288 AO: 61568 bytes free on soundcard 2006-09-16 03:40:00.297 AO: 59232 bytes free on soundcard 2006-09-16 03:40:00.304 AO: 60544 bytes free on soundcard 2006-09-16 03:40:00.313 AO: 62336 bytes free on soundcard 2006-09-16 03:40:00.320 AO: 59520 bytes free on soundcard 2006-09-16 03:40:00.331 AO: 61664 bytes free on soundcard 2006-09-16 03:40:00.336 AO: 58496 bytes free on soundcard 2006-09-16 03:40:00.346 AO: 60544 bytes free on soundcard 2006-09-16 03:40:00.352 AO: 61568 bytes free on soundcard 2006-09-16 03:40:00.361 AO: 59008 bytes free on soundcard 2006-09-16 03:40:00.368 AO: 60576 bytes free on soundcard 2006-09-16 03:40:00.377 AO: 62432 bytes free on soundcard 2006-09-16 03:40:00.384 AO: 59520 bytes free on soundcard 2006-09-16 03:40:00.392 AO: 61056 bytes free on soundcard 2006-09-16 03:40:00.400 AO: 62592 bytes free on soundcard 2006-09-16 03:40:00.408 AO: 60032 bytes free on soundcard 2006-09-16 03:40:00.416 AO: 61568 bytes free on soundcard 2006-09-16 03:40:00.424 AO: 59008 bytes free on soundcard 2006-09-16 03:40:00.434 AO: 61056 bytes free on soundcard 2006-09-16 03:40:00.440 AO: 62080 bytes free on soundcard 2006-09-16 03:40:00.450 AO: 60000 bytes free on soundcard 2006-09-16 03:40:00.458 AO: 61440 bytes free on soundcard 2006-09-16 03:40:00.465 AO: 58688 bytes free on soundcard 2006-09-16 03:40:00.472 AO: 60096 bytes free on soundcard 2006-09-16 03:40:00.482 AO: 61984 bytes free on soundcard 2006-09-16 03:40:00.488 AO: 59104 bytes free on soundcard 2006-09-16 03:40:00.500 AO: 60960 bytes free on soundcard 2006-09-16 03:40:00.508 AO: 62912 bytes free on soundcard 2006-09-16 03:40:00.516 AO: 60384 bytes free on soundcard 2006-09-16 03:40:00.524 AO: 61856 bytes free on soundcard 2006-09-16 03:40:00.534 AO: 59776 bytes free on soundcard 2006-09-16 03:40:00.540 AO: 60800 bytes free on soundcard 2006-09-16 03:40:00.548 AO: 62368 bytes free on soundcard 2006-09-16 03:40:00.556 AO: 59808 bytes free on soundcard 2006-09-16 03:40:00.564 AO: 61312 bytes free on soundcard 2006-09-16 03:40:00.572 AO: 62880 bytes free on soundcard 2006-09-16 03:40:00.580 AO: 60320 bytes free on soundcard 2006-09-16 03:40:00.588 AO: 61856 bytes free on soundcard 2006-09-16 03:40:00.596 AO: 59296 bytes free on soundcard 2006-09-16 03:40:00.604 AO: 60864 bytes free on soundcard 2006-09-16 03:40:00.612 AO: 62368 bytes free on soundcard 2006-09-16 03:40:00.619 OSD: InitCC708() -- begin 2006-09-16 03:40:00.620 AO: 59808 bytes free on soundcard 2006-09-16 03:40:00.628 AO: 61376 bytes free on soundcard 2006-09-16 03:40:00.637 AO: 63072 bytes free on soundcard 2006-09-16 03:40:00.644 AO: 60288 bytes free on soundcard 2006-09-16 03:40:00.654 AO: 62368 bytes free on soundcard 2006-09-16 03:40:00.660 AO: 59296 bytes free on soundcard 2006-09-16 03:40:00.670 AO: 61280 bytes free on soundcard 2006-09-16 03:40:00.676 AO: 62368 bytes free on soundcard 2006-09-16 03:40:00.684 AO: 59872 bytes free on soundcard 2006-09-16 03:40:00.692 AO: 61312 bytes free on soundcard 2006-09-16 03:40:00.700 AO: 62912 bytes free on soundcard 2006-09-16 03:40:00.709 AO: 60608 bytes free on soundcard 2006-09-16 03:40:00.716 AO: 61984 bytes free on soundcard 2006-09-16 03:40:00.724 AO: 59328 bytes free on soundcard 2006-09-16 03:40:00.732 AO: 60864 bytes free on soundcard 2006-09-16 03:40:00.740 AO: 62336 bytes free on soundcard 2006-09-16 03:40:00.748 AO: 59840 bytes free on soundcard 2006-09-16 03:40:00.756 AO: 61344 bytes free on soundcard 2006-09-16 03:40:00.764 AO: 62912 bytes free on soundcard 2006-09-16 03:40:00.773 AO: 60512 bytes free on soundcard 2006-09-16 03:40:00.781 AO: 62048 bytes free on soundcard 2006-09-16 03:40:00.791 AO: 59904 bytes free on soundcard 2006-09-16 03:40:00.796 AO: 60864 bytes free on soundcard 2006-09-16 03:40:00.807 AO: 63008 bytes free on soundcard 2006-09-16 03:40:00.816 AO: 60576 bytes free on soundcard 2006-09-16 03:40:00.824 AO: 62080 bytes free on soundcard 2006-09-16 03:40:00.832 AO: 59520 bytes free on soundcard 2006-09-16 03:40:00.840 AO: 61184 bytes free on soundcard 2006-09-16 03:40:00.848 AO: 62592 bytes free on soundcard 2006-09-16 03:40:00.856 AO: 60096 bytes free on soundcard 2006-09-16 03:40:00.864 AO: 61600 bytes free on soundcard 2006-09-16 03:40:00.872 AO: 59040 bytes free on soundcard 2006-09-16 03:40:00.880 AO: 60576 bytes free on soundcard 2006-09-16 03:40:00.889 AO: 62272 bytes free on soundcard 2006-09-16 03:40:00.896 AO: 59552 bytes free on soundcard 2006-09-16 03:40:00.905 AO: 61440 bytes free on soundcard 2006-09-16 03:40:00.912 AO: 58528 bytes free on soundcard 2006-09-16 03:40:00.924 AO: 60800 bytes free on soundcard 2006-09-16 03:40:00.932 AO: 62368 bytes free on soundcard 2006-09-16 03:40:00.940 AO: 59840 bytes free on soundcard 2006-09-16 03:40:00.948 AO: 61344 bytes free on soundcard 2006-09-16 03:40:00.957 AO: 63168 bytes free on soundcard 2006-09-16 03:40:00.964 AO: 60320 bytes free on soundcard 2006-09-16 03:40:00.972 AO: 61920 bytes free on soundcard 2006-09-16 03:40:00.980 AO: 59328 bytes free on soundcard 2006-09-16 03:40:00.988 AO: 60896 bytes free on soundcard 2006-09-16 03:40:01.001 AO: 63360 bytes free on soundcard 2006-09-16 03:40:01.008 AO: 60608 bytes free on soundcard 2006-09-16 03:40:01.017 AO: 62336 bytes free on soundcard 2006-09-16 03:40:01.024 AO: 59552 bytes free on soundcard 2006-09-16 03:40:01.036 AO: 61984 bytes free on soundcard 2006-09-16 03:40:01.044 AO: 59296 bytes free on soundcard 2006-09-16 03:40:01.053 AO: 61184 bytes free on soundcard 2006-09-16 03:40:01.060 AO: 62368 bytes free on soundcard 2006-09-16 03:40:01.069 AO: 60160 bytes free on soundcard 2006-09-16 03:40:01.076 AO: 61344 bytes free on soundcard 2006-09-16 03:40:01.086 AO: 63392 bytes free on soundcard 2006-09-16 03:40:01.092 AO: 60352 bytes free on soundcard 2006-09-16 03:40:01.101 AO: 62048 bytes free on soundcard 2006-09-16 03:40:01.108 AO: 59296 bytes free on soundcard 2006-09-16 03:40:01.117 AO: 61088 bytes free on soundcard 2006-09-16 03:40:01.124 AO: 62368 bytes free on soundcard 2006-09-16 03:40:01.136 AO: 60736 bytes free on soundcard 2006-09-16 03:40:01.145 AO: 62400 bytes free on soundcard 2006-09-16 03:40:01.153 AO: 59808 bytes free on soundcard 2006-09-16 03:40:01.160 AO: 61120 bytes free on soundcard 2006-09-16 03:40:01.170 AO: 63040 bytes free on soundcard 2006-09-16 03:40:01.180 AO: 60864 bytes free on soundcard 2006-09-16 03:40:01.188 AO: 62368 bytes free on soundcard 2006-09-16 03:40:01.196 AO: 59808 bytes free on soundcard 2006-09-16 03:40:01.204 AO: 61344 bytes free on soundcard 2006-09-16 03:40:01.212 AO: 62912 bytes free on soundcard 2006-09-16 03:40:01.220 AO: 60320 bytes free on soundcard 2006-09-16 03:40:01.228 AO: 61856 bytes free on soundcard 2006-09-16 03:40:01.236 AO: 59328 bytes free on soundcard 2006-09-16 03:40:01.244 AO: 60864 bytes free on soundcard 2006-09-16 03:40:01.253 AO: 62624 bytes free on soundcard 2006-09-16 03:40:01.260 AO: 59840 bytes free on soundcard 2006-09-16 03:40:01.268 AO: 61344 bytes free on soundcard 2006-09-16 03:40:01.276 AO: 62880 bytes free on soundcard 2006-09-16 03:40:01.286 AO: 60480 bytes free on soundcard 2006-09-16 03:40:01.292 AO: 61856 bytes free on soundcard 2006-09-16 03:40:01.300 AO: 59296 bytes free on soundcard 2006-09-16 03:40:01.308 AO: 60864 bytes free on soundcard 2006-09-16 03:40:01.316 AO: 62368 bytes free on soundcard 2006-09-16 03:40:01.324 AO: 59808 bytes free on soundcard 2006-09-16 03:40:01.332 AO: 61376 bytes free on soundcard 2006-09-16 03:40:01.340 AO: 62880 bytes free on soundcard 2006-09-16 03:40:01.348 AO: 60320 bytes free on soundcard 2006-09-16 03:40:01.356 AO: 61888 bytes free on soundcard 2006-09-16 03:40:01.364 AO: 59296 bytes free on soundcard 2006-09-16 03:40:01.373 AO: 61184 bytes free on soundcard 2006-09-16 03:40:01.380 AO: 62400 bytes free on soundcard 2006-09-16 03:40:01.391 AO: 60544 bytes free on soundcard 2006-09-16 03:40:01.400 AO: 62112 bytes free on soundcard 2006-09-16 03:40:01.409 AO: 59808 bytes free on soundcard 2006-09-16 03:40:01.410 OSD: InitCC708() -- end 2006-09-16 03:40:01.416 AO: 61120 bytes free on soundcard 2006-09-16 03:40:01.428 AO: 63456 bytes free on soundcard 2006-09-16 03:40:01.436 AO: 60896 bytes free on soundcard 2006-09-16 03:40:01.444 AO: 62432 bytes free on soundcard 2006-09-16 03:40:01.452 AO: 59840 bytes free on soundcard 2006-09-16 03:40:01.460 AO: 61504 bytes free on soundcard 2006-09-16 03:40:01.468 AO: 58784 bytes free on soundcard 2006-09-16 03:40:01.476 AO: 60416 bytes free on soundcard 2006-09-16 03:40:01.486 AO: 62368 bytes free on soundcard 2006-09-16 03:40:01.492 AO: 59360 bytes free on soundcard 2006-09-16 03:40:01.500 AO: 60896 bytes free on soundcard 2006-09-16 03:40:01.509 AO: 62688 bytes free on soundcard 2006-09-16 03:40:01.516 AO: 59808 bytes free on soundcard 2006-09-16 03:40:01.525 AO: 61568 bytes free on soundcard 2006-09-16 03:40:01.532 AO: 58784 bytes free on soundcard 2006-09-16 03:40:01.540 AO: 60384 bytes free on soundcard 2006-09-16 03:40:01.548 AO: 61888 bytes free on soundcard 2006-09-16 03:40:01.556 AO: 59360 bytes free on soundcard 2006-09-16 03:40:01.564 AO: 60864 bytes free on soundcard 2006-09-16 03:40:01.572 AO: 62464 bytes free on soundcard 2006-09-16 03:40:01.580 AO: 59840 bytes free on soundcard 2006-09-16 03:40:01.589 AO: 61568 bytes free on soundcard 2006-09-16 03:40:01.596 AO: 58816 bytes free on soundcard 2006-09-16 03:40:01.608 AO: 61120 bytes free on soundcard 2006-09-16 03:40:01.616 AO: 62656 bytes free on soundcard 2006-09-16 03:40:01.624 AO: 60192 bytes free on soundcard 2006-09-16 03:40:01.632 AO: 61632 bytes free on soundcard 2006-09-16 03:40:01.643 AO: 59680 bytes free on soundcard 2006-09-16 03:40:01.648 AO: 60608 bytes free on soundcard 2006-09-16 03:40:01.659 AO: 62752 bytes free on soundcard 2006-09-16 03:40:01.664 AO: 59552 bytes free on soundcard 2006-09-16 03:40:01.675 AO: 61760 bytes free on soundcard 2006-09-16 03:40:01.680 AO: 58560 bytes free on soundcard 2006-09-16 03:40:01.692 AO: 60928 bytes free on soundcard 2006-09-16 03:40:01.700 AO: 62400 bytes free on soundcard 2006-09-16 03:40:01.711 AO: 60512 bytes free on soundcard 2006-09-16 03:40:01.720 AO: 62176 bytes free on soundcard 2006-09-16 03:40:01.728 AO: 59616 bytes free on soundcard 2006-09-16 03:40:01.736 AO: 61088 bytes free on soundcard 2006-09-16 03:40:01.745 AO: 62944 bytes free on soundcard 2006-09-16 03:40:01.753 AO: 60416 bytes free on soundcard 2006-09-16 03:40:01.760 AO: 61632 bytes free on soundcard 2006-09-16 03:40:01.768 AO: 59072 bytes free on soundcard 2006-09-16 03:40:01.777 AO: 60928 bytes free on soundcard 2006-09-16 03:40:01.784 AO: 62112 bytes free on soundcard 2006-09-16 03:40:01.794 AO: 59936 bytes free on soundcard 2006-09-16 03:40:01.800 AO: 61088 bytes free on soundcard 2006-09-16 03:40:01.810 AO: 63008 bytes free on soundcard 2006-09-16 03:40:01.816 AO: 60096 bytes free on soundcard 2006-09-16 03:40:01.824 AO: 61664 bytes free on soundcard 2006-09-16 03:40:01.832 AO: 59072 bytes free on soundcard 2006-09-16 03:40:01.840 AO: 60608 bytes free on soundcard 2006-09-16 03:40:01.848 AO: 62144 bytes free on soundcard 2006-09-16 03:40:01.856 AO: 59584 bytes free on soundcard 2006-09-16 03:40:01.864 AO: 61120 bytes free on soundcard 2006-09-16 03:40:01.872 AO: 62656 bytes free on soundcard 2006-09-16 03:40:01.881 AO: 60352 bytes free on soundcard 2006-09-16 03:40:01.888 AO: 61632 bytes free on soundcard 2006-09-16 03:40:01.897 AO: 59296 bytes free on soundcard 2006-09-16 03:40:01.904 AO: 60608 bytes free on soundcard 2006-09-16 03:40:01.917 AO: 63168 bytes free on soundcard 2006-09-16 03:40:01.924 AO: 60352 bytes free on soundcard 2006-09-16 03:40:01.932 AO: 61920 bytes free on soundcard 2006-09-16 03:40:01.940 AO: 59392 bytes free on soundcard 2006-09-16 03:40:01.948 AO: 60928 bytes free on soundcard 2006-09-16 03:40:01.956 AO: 62400 bytes free on soundcard 2006-09-16 03:40:01.966 AO: 60224 bytes free on soundcard 2006-09-16 03:40:01.972 AO: 61376 bytes free on soundcard 2006-09-16 03:40:01.980 AO: 62976 bytes free on soundcard 2006-09-16 03:40:01.988 AO: 60384 bytes free on soundcard 2006-09-16 03:40:01.996 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.004 AO: 59328 bytes free on soundcard 2006-09-16 03:40:02.012 AO: 60992 bytes free on soundcard 2006-09-16 03:40:02.020 AO: 62400 bytes free on soundcard 2006-09-16 03:40:02.028 AO: 59904 bytes free on soundcard 2006-09-16 03:40:02.037 AO: 61696 bytes free on soundcard 2006-09-16 03:40:02.044 AO: 58976 bytes free on soundcard 2006-09-16 03:40:02.052 AO: 60384 bytes free on soundcard 2006-09-16 03:40:02.061 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.068 AO: 59392 bytes free on soundcard 2006-09-16 03:40:02.076 AO: 60928 bytes free on soundcard 2006-09-16 03:40:02.086 AO: 62816 bytes free on soundcard 2006-09-16 03:40:02.092 AO: 59904 bytes free on soundcard 2006-09-16 03:40:02.100 AO: 61408 bytes free on soundcard 2006-09-16 03:40:02.108 AO: 62976 bytes free on soundcard 2006-09-16 03:40:02.116 AO: 60352 bytes free on soundcard 2006-09-16 03:40:02.124 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.132 AO: 59360 bytes free on soundcard 2006-09-16 03:40:02.140 AO: 60960 bytes free on soundcard 2006-09-16 03:40:02.148 AO: 62400 bytes free on soundcard 2006-09-16 03:40:02.156 AO: 59904 bytes free on soundcard 2006-09-16 03:40:02.164 AO: 61376 bytes free on soundcard 2006-09-16 03:40:02.172 AO: 62976 bytes free on soundcard 2006-09-16 03:40:02.180 AO: 60384 bytes free on soundcard 2006-09-16 03:40:02.188 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.196 AO: 59360 bytes free on soundcard 2006-09-16 03:40:02.204 AO: 60960 bytes free on soundcard 2006-09-16 03:40:02.216 AO: 63296 bytes free on soundcard 2006-09-16 03:40:02.224 AO: 60608 bytes free on soundcard 2006-09-16 03:40:02.234 AO: 62624 bytes free on soundcard 2006-09-16 03:40:02.240 AO: 59648 bytes free on soundcard 2006-09-16 03:40:02.251 AO: 61824 bytes free on soundcard 2006-09-16 03:40:02.260 AO: 59328 bytes free on soundcard 2006-09-16 03:40:02.269 AO: 61152 bytes free on soundcard 2006-09-16 03:40:02.276 AO: 62432 bytes free on soundcard 2006-09-16 03:40:02.285 AO: 60096 bytes free on soundcard 2006-09-16 03:40:02.292 AO: 61504 bytes free on soundcard 2006-09-16 03:40:02.300 AO: 58912 bytes free on soundcard 2006-09-16 03:40:02.308 AO: 60384 bytes free on soundcard 2006-09-16 03:40:02.316 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.324 AO: 59328 bytes free on soundcard 2006-09-16 03:40:02.335 AO: 61504 bytes free on soundcard 2006-09-16 03:40:02.340 AO: 58304 bytes free on soundcard 2006-09-16 03:40:02.348 AO: 59904 bytes free on soundcard 2006-09-16 03:40:02.356 AO: 61408 bytes free on soundcard 2006-09-16 03:40:02.364 AO: 62976 bytes free on soundcard 2006-09-16 03:40:02.372 AO: 60352 bytes free on soundcard 2006-09-16 03:40:02.380 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.388 AO: 59328 bytes free on soundcard 2006-09-16 03:40:02.396 AO: 60928 bytes free on soundcard 2006-09-16 03:40:02.404 AO: 62432 bytes free on soundcard 2006-09-16 03:40:02.412 AO: 59872 bytes free on soundcard 2006-09-16 03:40:02.420 AO: 61408 bytes free on soundcard 2006-09-16 03:40:02.428 AO: 62944 bytes free on soundcard 2006-09-16 03:40:02.436 AO: 60352 bytes free on soundcard 2006-09-16 03:40:02.444 AO: 61952 bytes free on soundcard 2006-09-16 03:40:02.455 AO: 59904 bytes free on soundcard 2006-09-16 03:40:02.460 AO: 60896 bytes free on soundcard 2006-09-16 03:40:02.471 AO: 62976 bytes free on soundcard 2006-09-16 03:40:02.476 AO: 59872 bytes free on soundcard 2006-09-16 03:40:02.487 AO: 62080 bytes free on soundcard 2006-09-16 03:40:02.496 AO: 59648 bytes free on soundcard 2006-09-16 03:40:02.504 AO: 61216 bytes free on soundcard 2006-09-16 03:40:02.512 AO: 62752 bytes free on soundcard 2006-09-16 03:40:02.520 AO: 60128 bytes free on soundcard 2006-09-16 03:40:02.528 AO: 61728 bytes free on soundcard 2006-09-16 03:40:02.539 AO: 59712 bytes free on soundcard 2006-09-16 03:40:02.545 AO: 60832 bytes free on soundcard 2006-09-16 03:40:02.555 AO: 62816 bytes free on soundcard 2006-09-16 03:40:02.560 AO: 59680 bytes free on soundcard 2006-09-16 03:40:02.568 AO: 61152 bytes free on soundcard 2006-09-16 03:40:02.576 AO: 62720 bytes free on soundcard 2006-09-16 03:40:02.584 AO: 60128 bytes free on soundcard 2006-09-16 03:40:02.592 AO: 61728 bytes free on soundcard 2006-09-16 03:40:02.600 AO: 59104 bytes free on soundcard 2006-09-16 03:40:02.608 AO: 60704 bytes free on soundcard 2006-09-16 03:40:02.616 AO: 62176 bytes free on soundcard 2006-09-16 03:40:02.624 AO: 59616 bytes free on soundcard 2006-09-16 03:40:02.632 AO: 61152 bytes free on soundcard 2006-09-16 03:40:02.641 AO: 62848 bytes free on soundcard 2006-09-16 03:40:02.648 AO: 60128 bytes free on soundcard 2006-09-16 03:40:02.656 AO: 61792 bytes free on soundcard 2006-09-16 03:40:02.664 AO: 59136 bytes free on soundcard 2006-09-16 03:40:02.672 AO: 60704 bytes free on soundcard 2006-09-16 03:40:02.680 AO: 62176 bytes free on soundcard 2006-09-16 03:40:02.692 AO: 60352 bytes free on soundcard 2006-09-16 03:40:02.700 AO: 61920 bytes free on soundcard 2006-09-16 03:40:02.710 AO: 59872 bytes free on soundcard 2006-09-16 03:40:02.716 AO: 60928 bytes free on soundcard 2006-09-16 03:40:02.729 AO: 63232 bytes free on soundcard 2006-09-16 03:40:02.731 NVP: ClearAfterSeek(1) 2006-09-16 03:40:02.731 VideoOutputXv: ClearAfterSeek() 2006-09-16 03:40:02.732 VideoOutputXv: DiscardFrames(0) 2006-09-16 03:40:02.732 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAAAA 2006-09-16 03:40:02.733 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done() 2006-09-16 03:40:02.733 VideoOutputXv: DiscardFrames(1) 2006-09-16 03:40:02.734 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAAAA 2006-09-16 03:40:02.734 VideoOutputXv: DiscardFrames() 2: AAAAAAAAAAAAAAAA 2006-09-16 03:40:02.734 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done() 2006-09-16 03:40:02.736 TV: StartPlayer(): took 3201 ms to start player. 2006-09-16 03:40:02.736 TV: Changing from None to WatchingLiveTV 2006-09-16 03:40:02.737 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:02.737 AO: 60928 bytes free on soundcard 2006-09-16 03:40:02.745 AO: 62400 bytes free on soundcard 2006-09-16 03:40:02.747 write -> 15 35 QUERY_RECORDER 1[]:[]FRONTEND_READY 2006-09-16 03:40:02.749 read <- 15 2 ok 2006-09-16 03:40:02.749 write -> 15 30 QUERY_RECORDER 1[]:[]GET_INPUT 2006-09-16 03:40:02.750 read <- 15 10 television 2006-09-16 03:40:02.752 AO: 59648 bytes free on soundcard 2006-09-16 03:40:02.760 AO: 61184 bytes free on soundcard 2006-09-16 03:40:02.762 The realtime priority setting is not enabled. 2006-09-16 03:40:02.768 AO: 62720 bytes free on soundcard 2006-09-16 03:40:02.776 AO: 60128 bytes free on soundcard 2006-09-16 03:40:02.784 AO: 61760 bytes free on soundcard 2006-09-16 03:40:02.792 AO: 59104 bytes free on soundcard 2006-09-16 03:40:02.800 AO: 60704 bytes free on soundcard 2006-09-16 03:40:02.808 AO: 62176 bytes free on soundcard 2006-09-16 03:40:02.816 AO: 59648 bytes free on soundcard 2006-09-16 03:40:02.825 AO: 61440 bytes free on soundcard 2006-09-16 03:40:02.833 AO: 58912 bytes free on soundcard 2006-09-16 03:40:02.840 AO: 60160 bytes free on soundcard 2006-09-16 03:40:02.848 AO: 61728 bytes free on soundcard 2006-09-16 03:40:03.198 AO: 65536 bytes free on soundcard 2006-09-16 03:40:03.208 AO: 63232 bytes free on soundcard 2006-09-16 03:40:03.230 AO: 63456 bytes free on soundcard 2006-09-16 03:40:03.237 AO: 60576 bytes free on soundcard 2006-09-16 03:40:03.244 AO: 61952 bytes free on soundcard 2006-09-16 03:40:03.254 AO: 59776 bytes free on soundcard 2006-09-16 03:40:03.260 AO: 60960 bytes free on soundcard 2006-09-16 03:40:03.268 AO: 62560 bytes free on soundcard 2006-09-16 03:40:03.276 AO: 59936 bytes free on soundcard 2006-09-16 03:40:03.277 nVidiaVideoSync: Could not open device /dev/nvidia0, No such device or address 2006-09-16 03:40:03.292 AO: 62944 bytes free on soundcard 2006-09-16 03:40:03.303 AO: 61024 bytes free on soundcard 2006-09-16 03:40:03.304 AFD: video timecode 90039 79239 880 612 2006-09-16 03:40:03.311 AFD: audio timecode 81011 81011 900 924 2006-09-16 03:40:03.311 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=900 2006-09-16 03:40:03.314 AFD: audio timecode 83171 83171 924 948 2006-09-16 03:40:03.314 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=924 2006-09-16 03:40:03.317 AO: 63776 bytes free on soundcard 2006-09-16 03:40:03.322 AFD: audio timecode 85331 85331 948 972 2006-09-16 03:40:03.322 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=948 2006-09-16 03:40:03.324 AFD: audio timecode 87491 87491 972 996 2006-09-16 03:40:03.324 AO: _AddSamples bytes=4608, used=13825, free=754175, timecode=972 2006-09-16 03:40:03.328 AO: 61696 bytes free on soundcard 2006-09-16 03:40:03.342 AO: 60320 bytes free on soundcard 2006-09-16 03:40:03.348 AO: 61472 bytes free on soundcard 2006-09-16 03:40:03.348 AFD: video timecode 82839 82839 920 996 2006-09-16 03:40:03.358 AFD: audio timecode 89651 89651 996 1020 2006-09-16 03:40:03.358 AO: _AddSamples bytes=4608, used=18433, free=749567, timecode=996 2006-09-16 03:40:03.361 AO: 59872 bytes free on soundcard 2006-09-16 03:40:03.369 AO: 61312 bytes free on soundcard 2006-09-16 03:40:03.375 Using audio as timebase 2006-09-16 03:40:03.375 Video timing method: DRM 2006-09-16 03:40:03.375 Refresh rate: 20000, frame interval: 40000 2006-09-16 03:40:03.376 AO: 62720 bytes free on soundcard 2006-09-16 03:40:03.380 AFD: video timecode 86439 86439 960 1020 2006-09-16 03:40:03.381 AFD: audio timecode 91811 91811 1020 1044 2006-09-16 03:40:03.382 AO: _AddSamples bytes=4608, used=23041, free=744959, timecode=1020 2006-09-16 03:40:03.383 AFD: audio timecode 93971 93971 1044 1068 2006-09-16 03:40:03.384 AO: _AddSamples bytes=4608, used=27649, free=740351, timecode=1044 2006-09-16 03:40:03.388 AO: 60960 bytes free on soundcard 2006-09-16 03:40:03.399 AO: 63136 bytes free on soundcard 2006-09-16 03:40:03.404 AFD: video timecode 100839 90039 1000 1068 2006-09-16 03:40:03.407 AFD: audio timecode 96131 96131 1068 1092 2006-09-16 03:40:03.408 AO: _AddSamples bytes=4608, used=32257, free=735743, timecode=1068 2006-09-16 03:40:03.409 AFD: audio timecode 98291 98291 1092 1116 2006-09-16 03:40:03.409 AO: _AddSamples bytes=4608, used=36865, free=731135, timecode=1092 2006-09-16 03:40:03.412 AO: 61472 bytes free on soundcard 2006-09-16 03:40:03.427 AO: 60160 bytes free on soundcard 2006-09-16 03:40:03.432 AFD: video timecode 93639 93639 1040 1116 2006-09-16 03:40:03.436 AO: 61952 bytes free on soundcard 2006-09-16 03:40:03.440 AFD: audio timecode 100451 100451 1116 1140 2006-09-16 03:40:03.440 AO: _AddSamples bytes=4608, used=41473, free=726527, timecode=1116 2006-09-16 03:40:03.452 AO: 60960 bytes free on soundcard 2006-09-16 03:40:03.460 AO: 62496 bytes free on soundcard 2006-09-16 03:40:03.464 AFD: video timecode 97239 97239 1080 1140 2006-09-16 03:40:03.472 AFD: audio timecode 102611 102611 1140 1164 2006-09-16 03:40:03.472 AO: _AddSamples bytes=4608, used=46081, free=721919, timecode=1140 2006-09-16 03:40:03.474 AFD: audio timecode 104771 104771 1164 1188 2006-09-16 03:40:03.474 AO: _AddSamples bytes=4608, used=50689, free=717311, timecode=1164 2006-09-16 03:40:03.476 AO: 61440 bytes free on soundcard 2006-09-16 03:40:03.487 AO: 59392 bytes free on soundcard 2006-09-16 03:40:03.500 AO: 61952 bytes free on soundcard 2006-09-16 03:40:03.508 AO: 59392 bytes free on soundcard 2006-09-16 03:40:03.512 AFD: video timecode 111638 100839 1120 1188 2006-09-16 03:40:03.513 AFD: audio timecode 106931 106931 1188 1212 2006-09-16 03:40:03.514 AO: _AddSamples bytes=4608, used=55297, free=712703, timecode=1188 2006-09-16 03:40:03.517 AFD: audio timecode 109090 109090 1212 1236 2006-09-16 03:40:03.519 AO: _AddSamples bytes=4608, used=59905, free=708095, timecode=1212 2006-09-16 03:40:03.522 AO: 62080 bytes free on soundcard 2006-09-16 03:40:03.532 AO: 59904 bytes free on soundcard 2006-09-16 03:40:03.544 AO: 62176 bytes free on soundcard 2006-09-16 03:40:03.544 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1 2006-09-16 03:40:03.548 AFD: video timecode 104439 104439 1160 1236 2006-09-16 03:40:03.553 AFD: audio timecode 111250 111250 1236 1260 2006-09-16 03:40:03.553 AO: _AddSamples bytes=4608, used=64513, free=703487, timecode=1236 2006-09-16 03:40:03.557 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:03.557 AO: Broadcasting free space avail 2006-09-16 03:40:03.557 AO: Broadcasting free space avail 2006-09-16 03:40:03.558 AO: Broadcasting free space avail 2006-09-16 03:40:03.558 AO: Broadcasting free space avail 2006-09-16 03:40:03.558 AO: Broadcasting free space avail 2006-09-16 03:40:03.558 AO: Broadcasting free space avail 2006-09-16 03:40:03.559 AO: Broadcasting free space avail 2006-09-16 03:40:03.559 AO: Broadcasting free space avail 2006-09-16 03:40:03.559 AO: Broadcasting free space avail 2006-09-16 03:40:03.560 AO: Broadcasting free space avail 2006-09-16 03:40:03.560 AO: Broadcasting free space avail 2006-09-16 03:40:03.560 AO: Broadcasting free space avail 2006-09-16 03:40:03.560 AO: Broadcasting free space avail 2006-09-16 03:40:03.561 AO: Broadcasting free space avail 2006-09-16 03:40:03.561 AO: audio waiting for space on soundcard: have 4032 need 4096 2006-09-16 03:40:03.579 AO: Broadcasting free space avail 2006-09-16 03:40:03.580 AO: audio waiting for space on soundcard: have 3520 need 4096 2006-09-16 03:40:03.584 AFD: video timecode 108038 108038 1200 1260 2006-09-16 03:40:03.589 AFD: audio timecode 113410 113410 1260 1284 2006-09-16 03:40:03.590 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1260 2006-09-16 03:40:03.594 AFD: audio timecode 115570 115570 1284 1308 2006-09-16 03:40:03.594 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=1284 2006-09-16 03:40:03.595 AFD: HandleGopStart: gopset not set, syncing positionMap 2006-09-16 03:40:03.595 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1) 2006-09-16 03:40:03.599 AO: Broadcasting free space avail 2006-09-16 03:40:03.599 AO: audio waiting for space on soundcard: have 3264 need 4096 2006-09-16 03:40:03.605 Position map filled from DB to: 6 2006-09-16 03:40:03.605 SyncPositionMap watchingrecording, from DB: 6 entries 2006-09-16 03:40:03.605 write -> 15 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN 2006-09-16 03:40:03.609 read <- 15 9 0[]:[]108 2006-09-16 03:40:03.609 Filling position map from 7 to 9 2006-09-16 03:40:03.610 write -> 15 50 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]7[]:[]9 2006-09-16 03:40:03.611 read <- 15 85 0[]:[]7[]:[]0[]:[]2316288[]:[]0[]:[]8[]:[]0[]:[]2697216[]:[]0[]:[... 2006-09-16 03:40:03.612 Position map filled from Encoder to: 9 2006-09-16 03:40:03.612 SyncPositionMap watchingrecording total: 9 entries 2006-09-16 03:40:03.612 SyncPositionMap, new totframes: 108, new length: 4, posMap size: 9 2006-09-16 03:40:03.613 AFD: HandleGopStart: Initial key frame distance: 12. 2006-09-16 03:40:03.616 AO: Broadcasting free space avail 2006-09-16 03:40:03.616 AO: audio waiting for space on soundcard: have 2368 need 4096 2006-09-16 03:40:03.632 AO: Broadcasting free space avail 2006-09-16 03:40:03.632 AO: audio waiting for space on soundcard: have 1344 need 4096 2006-09-16 03:40:03.644 AO: audio waiting for space on soundcard: have 3552 need 4096 2006-09-16 03:40:03.664 AO: Broadcasting free space avail 2006-09-16 03:40:03.664 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:03.665 AO: Broadcasting free space avail 2006-09-16 03:40:03.672 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:03.672 AO: Broadcasting free space avail 2006-09-16 03:40:03.678 AFD: video timecode 122438 111638 1240 1308 2006-09-16 03:40:03.680 AFD: audio timecode 117730 117730 1308 1332 2006-09-16 03:40:03.681 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1308 2006-09-16 03:40:03.682 AFD: audio timecode 119890 119890 1332 1356 2006-09-16 03:40:03.683 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=1332 2006-09-16 03:40:03.686 AO: Broadcasting free space avail 2006-09-16 03:40:03.686 AO: audio waiting for space on soundcard: have 3488 need 4096 2006-09-16 03:40:03.696 AO: Broadcasting free space avail 2006-09-16 03:40:03.696 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.696 AO: Broadcasting free space avail 2006-09-16 03:40:03.709 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.710 AO: Broadcasting free space avail 2006-09-16 03:40:03.717 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.717 AO: Broadcasting free space avail 2006-09-16 03:40:03.724 AFD: video timecode 115238 115238 1280 1356 2006-09-16 03:40:03.727 AFD: audio timecode 122050 122050 1356 1380 2006-09-16 03:40:03.728 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1356 2006-09-16 03:40:03.730 AO: Broadcasting free space avail 2006-09-16 03:40:03.731 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:03.731 AO: Broadcasting free space avail 2006-09-16 03:40:03.744 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:03.744 AO: Broadcasting free space avail 2006-09-16 03:40:03.747 NVP: A/V Divergence: -0.775, Rate: -0.775, Warpfactor: 0.9995, warpfactor_avg: 1 2006-09-16 03:40:03.752 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:03.752 AO: Broadcasting free space avail 2006-09-16 03:40:03.752 AFD: video timecode 118838 118838 1320 1380 2006-09-16 03:40:03.754 AFD: audio timecode 124210 124210 1380 1404 2006-09-16 03:40:03.754 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1380 2006-09-16 03:40:03.756 AFD: audio timecode 126370 126370 1404 1428 2006-09-16 03:40:03.756 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=1404 2006-09-16 03:40:03.761 AO: Broadcasting free space avail 2006-09-16 03:40:03.761 AO: Broadcasting free space avail 2006-09-16 03:40:03.761 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:03.762 AO: Broadcasting free space avail 2006-09-16 03:40:03.776 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:03.776 AO: Broadcasting free space avail 2006-09-16 03:40:03.788 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:03.792 AO: Broadcasting free space avail 2006-09-16 03:40:03.792 AFD: video timecode 133238 122438 1360 1428 2006-09-16 03:40:03.796 AFD: audio timecode 128530 128530 1428 1452 2006-09-16 03:40:03.796 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1428 2006-09-16 03:40:03.798 AFD: audio timecode 130690 130690 1452 1476 2006-09-16 03:40:03.800 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1452 2006-09-16 03:40:03.806 AO: Broadcasting free space avail 2006-09-16 03:40:03.806 AO: Broadcasting free space avail 2006-09-16 03:40:03.807 AO: audio waiting for space on soundcard: have 2080 need 4096 2006-09-16 03:40:03.823 AO: Broadcasting free space avail 2006-09-16 03:40:03.823 AO: Broadcasting free space avail 2006-09-16 03:40:03.828 AO: Broadcasting free space avail 2006-09-16 03:40:03.833 AFD: video timecode 126038 126038 1400 1476 2006-09-16 03:40:03.841 AFD: audio timecode 132850 132850 1476 1500 2006-09-16 03:40:03.841 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1476 2006-09-16 03:40:03.840 AO: Broadcasting free space avail 2006-09-16 03:40:03.848 AO: Broadcasting free space avail 2006-09-16 03:40:03.855 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:03.855 AO: Broadcasting free space avail 2006-09-16 03:40:03.859 NVP: A/V Divergence: -2.08125, Rate: -1.30625, Warpfactor: 0.999, warpfactor_avg: 1 2006-09-16 03:40:03.860 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:03.860 AO: Broadcasting free space avail 2006-09-16 03:40:03.880 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:03.880 AO: Broadcasting free space avail 2006-09-16 03:40:03.892 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:03.892 AO: Broadcasting free space avail 2006-09-16 03:40:03.893 AFD: video timecode 129638 129638 1440 1500 2006-09-16 03:40:03.901 AFD: audio timecode 135010 135010 1500 1524 2006-09-16 03:40:03.901 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1500 2006-09-16 03:40:03.906 AO: Broadcasting free space avail 2006-09-16 03:40:03.906 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:03.906 AO: Broadcasting free space avail 2006-09-16 03:40:03.903 AFD: audio timecode 137170 137170 1524 1548 2006-09-16 03:40:03.907 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1524 2006-09-16 03:40:03.910 NVP: A/V Divergence: -3.21718, Rate: -1.13593, Warpfactor: 0.998501, warpfactor_avg: 1 2006-09-16 03:40:03.916 AO: Broadcasting free space avail 2006-09-16 03:40:03.917 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.917 AO: Broadcasting free space avail 2006-09-16 03:40:03.932 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.932 AO: Broadcasting free space avail 2006-09-16 03:40:03.941 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.942 AO: Broadcasting free space avail 2006-09-16 03:40:03.944 AFD: video timecode 144038 133238 1480 1548 2006-09-16 03:40:03.948 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:03.948 AO: Broadcasting free space avail 2006-09-16 03:40:03.955 AFD: audio timecode 139330 139330 1548 1572 2006-09-16 03:40:03.955 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1548 2006-09-16 03:40:03.959 AFD: audio timecode 141490 141490 1572 1596 2006-09-16 03:40:03.959 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=1572 2006-09-16 03:40:03.960 AO: Broadcasting free space avail 2006-09-16 03:40:03.961 AO: Broadcasting free space avail 2006-09-16 03:40:03.961 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:03.961 AO: Broadcasting free space avail 2006-09-16 03:40:03.962 NVP: Video is 3.21718 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:03.967 NVP: A/V Divergence: -4.16913, Rate: -0.95195, Warpfactor: 0.998001, warpfactor_avg: 1 2006-09-16 03:40:03.968 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:03.969 AO: Broadcasting free space avail 2006-09-16 03:40:03.978 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:03.979 AO: Broadcasting free space avail 2006-09-16 03:40:03.991 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:03.991 AO: Broadcasting free space avail 2006-09-16 03:40:03.997 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:03.997 AO: Broadcasting free space avail 2006-09-16 03:40:03.970 NVP: Video is 4.16913 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.012 AFD: video timecode 136838 136838 1520 1596 2006-09-16 03:40:04.014 AFD: audio timecode 143650 143650 1596 1620 2006-09-16 03:40:04.014 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1596 2006-09-16 03:40:04.015 AO: Broadcasting free space avail 2006-09-16 03:40:04.015 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:04.015 AO: Broadcasting free space avail 2006-09-16 03:40:04.020 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:04.020 AO: Broadcasting free space avail 2006-09-16 03:40:04.028 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:04.028 AO: Broadcasting free space avail 2006-09-16 03:40:04.030 NVP: A/V Divergence: -4.84557, Rate: -0.67645, Warpfactor: 0.997502, warpfactor_avg: 0.999999 2006-09-16 03:40:04.033 NVP: Video is 4.84557 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.036 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:04.036 AO: Broadcasting free space avail 2006-09-16 03:40:04.050 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:04.051 AO: Broadcasting free space avail 2006-09-16 03:40:04.062 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:04.062 AO: Broadcasting free space avail 2006-09-16 03:40:04.069 AFD: video timecode 140438 140438 1560 1620 2006-09-16 03:40:04.071 AFD: audio timecode 145810 145810 1620 1644 2006-09-16 03:40:04.071 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1620 2006-09-16 03:40:04.078 AFD: audio timecode 147970 147970 1644 1668 2006-09-16 03:40:04.079 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1644 2006-09-16 03:40:04.080 AO: Broadcasting free space avail 2006-09-16 03:40:04.082 AO: Broadcasting free space avail 2006-09-16 03:40:04.083 AO: Broadcasting free space avail 2006-09-16 03:40:04.083 AO: Broadcasting free space avail 2006-09-16 03:40:04.084 NVP: A/V Divergence: -5.32793, Rate: -0.48235, Warpfactor: 0.997004, warpfactor_avg: 0.999999 2006-09-16 03:40:04.088 AO: Broadcasting free space avail 2006-09-16 03:40:04.089 NVP: Video is 5.32793 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.095 NVP: A/V Divergence: -5.78968, Rate: -0.46175, Warpfactor: 0.996505, warpfactor_avg: 0.999999 2006-09-16 03:40:04.096 AO: Broadcasting free space avail 2006-09-16 03:40:04.110 AO: Broadcasting free space avail 2006-09-16 03:40:04.116 AFD: video timecode 154838 144038 1600 1668 2006-09-16 03:40:04.117 AFD: audio timecode 150130 150130 1668 1692 2006-09-16 03:40:04.118 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1668 2006-09-16 03:40:04.119 AFD: audio timecode 152290 152290 1692 1716 2006-09-16 03:40:04.120 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=1692 2006-09-16 03:40:04.123 AO: Broadcasting free space avail 2006-09-16 03:40:04.123 AO: Broadcasting free space avail 2006-09-16 03:40:04.124 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.124 AO: Broadcasting free space avail 2006-09-16 03:40:04.137 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.138 AO: Broadcasting free space avail 2006-09-16 03:40:04.144 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.144 AO: Broadcasting free space avail 2006-09-16 03:40:04.148 AFD: video timecode 147638 147638 1640 1716 2006-09-16 03:40:04.151 AFD: audio timecode 154450 154450 1716 1740 2006-09-16 03:40:04.155 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1716 2006-09-16 03:40:04.156 AO: Broadcasting free space avail 2006-09-16 03:40:04.156 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.156 AO: Broadcasting free space avail 2006-09-16 03:40:04.158 NVP: Video is 5.78968 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.164 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.165 AO: Broadcasting free space avail 2006-09-16 03:40:04.167 NVP: A/V Divergence: -6.31725, Rate: -0.527575, Warpfactor: 0.996007, warpfactor_avg: 0.999999 2006-09-16 03:40:04.173 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.173 AO: Broadcasting free space avail 2006-09-16 03:40:04.177 NVP: Video is 6.31725 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.180 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.181 AO: Broadcasting free space avail 2006-09-16 03:40:04.190 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.191 AO: Broadcasting free space avail 2006-09-16 03:40:04.203 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.203 AO: Broadcasting free space avail 2006-09-16 03:40:04.208 AFD: video timecode 151238 151238 1680 1740 2006-09-16 03:40:04.210 AFD: audio timecode 156610 156610 1740 1764 2006-09-16 03:40:04.210 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1740 2006-09-16 03:40:04.212 AO: Broadcasting free space avail 2006-09-16 03:40:04.212 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:04.212 AO: Broadcasting free space avail 2006-09-16 03:40:04.211 AFD: audio timecode 158770 158770 1764 1788 2006-09-16 03:40:04.213 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1764 2006-09-16 03:40:04.214 NVP: A/V Divergence: -6.58168, Rate: -0.264425, Warpfactor: 0.995509, warpfactor_avg: 0.999999 2006-09-16 03:40:04.215 NVP: Video is 6.58168 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.220 NVP: A/V Divergence: -6.7675, Rate: -0.185825, Warpfactor: 0.995011, warpfactor_avg: 0.999998 2006-09-16 03:40:04.227 AO: Broadcasting free space avail 2006-09-16 03:40:04.227 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.228 AO: Broadcasting free space avail 2006-09-16 03:40:04.230 NVP: Video is 6.7675 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.235 NVP: A/V Divergence: -6.75063, Rate: 0.016875, Warpfactor: 0.994513, warpfactor_avg: 0.999998 2006-09-16 03:40:04.240 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.240 AO: Broadcasting free space avail 2006-09-16 03:40:04.250 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.251 AO: Broadcasting free space avail 2006-09-16 03:40:04.251 NVP: Video is 6.75063 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.255 NVP: A/V Divergence: -6.6192, Rate: 0.131425, Warpfactor: 0.994016, warpfactor_avg: 0.999997 2006-09-16 03:40:04.257 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.257 AO: Broadcasting free space avail 2006-09-16 03:40:04.259 NVP: Video is 6.6192 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.269 NVP: A/V Divergence: -6.3269, Rate: 0.2923, Warpfactor: 0.993519, warpfactor_avg: 0.999997 2006-09-16 03:40:04.271 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.272 AO: Broadcasting free space avail 2006-09-16 03:40:04.274 NVP: Video is 6.3269 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.283 NVP: A/V Divergence: -5.94518, Rate: 0.381725, Warpfactor: 0.993022, warpfactor_avg: 0.999996 2006-09-16 03:40:04.285 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.286 AO: Broadcasting free space avail 2006-09-16 03:40:04.293 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.293 AO: Broadcasting free space avail 2006-09-16 03:40:04.294 NVP: Video is 5.94518 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.304 NVP: A/V Divergence: -5.53387, Rate: 0.4113, Warpfactor: 0.992526, warpfactor_avg: 0.999996 2006-09-16 03:40:04.309 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.309 AO: Broadcasting free space avail 2006-09-16 03:40:04.321 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.322 AO: Broadcasting free space avail 2006-09-16 03:40:04.332 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.333 AO: Broadcasting free space avail 2006-09-16 03:40:04.336 NVP: Video is 5.53387 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.340 AFD: video timecode 165638 154838 1720 1788 2006-09-16 03:40:04.343 AFD: audio timecode 160930 160930 1788 1812 2006-09-16 03:40:04.350 NVP: A/V Divergence: -5.2379, Rate: 0.295975, Warpfactor: 0.99203, warpfactor_avg: 0.999995 2006-09-16 03:40:04.343 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1788 2006-09-16 03:40:04.352 AFD: audio timecode 163090 163090 1812 1836 2006-09-16 03:40:04.352 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=1812 2006-09-16 03:40:04.360 AO: Broadcasting free space avail 2006-09-16 03:40:04.361 AO: Broadcasting free space avail 2006-09-16 03:40:04.361 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.362 AO: Broadcasting free space avail 2006-09-16 03:40:04.363 NVP: Video is 5.2379 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.366 NVP: A/V Divergence: -4.92842, Rate: 0.309475, Warpfactor: 0.991534, warpfactor_avg: 0.999995 2006-09-16 03:40:04.377 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.377 AO: Broadcasting free space avail 2006-09-16 03:40:04.391 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.391 AO: Broadcasting free space avail 2006-09-16 03:40:04.395 NVP: Video is 4.92842 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.400 AFD: video timecode 158438 158438 1760 1836 2006-09-16 03:40:04.404 AFD: audio timecode 165250 165250 1836 1860 2006-09-16 03:40:04.405 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=1836 2006-09-16 03:40:04.407 AO: Broadcasting free space avail 2006-09-16 03:40:04.407 AO: Broadcasting free space avail 2006-09-16 03:40:04.407 AO: Broadcasting free space avail 2006-09-16 03:40:04.411 NVP: A/V Divergence: -4.6588, Rate: 0.269625, Warpfactor: 0.991038, warpfactor_avg: 0.999994 2006-09-16 03:40:04.416 AO: Broadcasting free space avail 2006-09-16 03:40:04.425 AO: Broadcasting free space avail 2006-09-16 03:40:04.426 NVP: Video is 4.6588 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.433 NVP: A/V Divergence: -4.3941, Rate: 0.2647, Warpfactor: 0.990542, warpfactor_avg: 0.999994 2006-09-16 03:40:04.434 AO: Broadcasting free space avail 2006-09-16 03:40:04.443 AO: Broadcasting free space avail 2006-09-16 03:40:04.444 NVP: Video is 4.3941 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.448 NVP: A/V Divergence: -4.05807, Rate: 0.336025, Warpfactor: 0.990047, warpfactor_avg: 0.999993 2006-09-16 03:40:04.449 AO: Broadcasting free space avail 2006-09-16 03:40:04.464 AO: Broadcasting free space avail 2006-09-16 03:40:04.475 AO: Broadcasting free space avail 2006-09-16 03:40:04.476 AFD: video timecode 162038 162038 1800 1860 2006-09-16 03:40:04.479 AFD: audio timecode 167410 167410 1860 1884 2006-09-16 03:40:04.481 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1860 2006-09-16 03:40:04.483 AFD: audio timecode 169570 169570 1884 1908 2006-09-16 03:40:04.483 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=1884 2006-09-16 03:40:04.489 AO: Broadcasting free space avail 2006-09-16 03:40:04.489 AO: Broadcasting free space avail 2006-09-16 03:40:04.490 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.490 AO: Broadcasting free space avail 2006-09-16 03:40:04.504 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.505 AO: Broadcasting free space avail 2006-09-16 03:40:04.512 AFD: video timecode 176438 165638 1840 1908 2006-09-16 03:40:04.514 AFD: audio timecode 171730 171730 1908 1932 2006-09-16 03:40:04.514 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1908 2006-09-16 03:40:04.516 AO: Broadcasting free space avail 2006-09-16 03:40:04.516 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:04.517 AO: Broadcasting free space avail 2006-09-16 03:40:04.519 AFD: audio timecode 173889 173889 1932 1956 2006-09-16 03:40:04.520 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1932 2006-09-16 03:40:04.532 AO: Broadcasting free space avail 2006-09-16 03:40:04.533 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:04.533 AO: Broadcasting free space avail 2006-09-16 03:40:04.543 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:04.544 AO: Broadcasting free space avail 2006-09-16 03:40:04.544 NVP: Video is 4.05807 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.548 AFD: video timecode 169238 169238 1880 1956 2006-09-16 03:40:04.550 AFD: audio timecode 176049 176049 1956 1980 2006-09-16 03:40:04.550 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1956 2006-09-16 03:40:04.558 NVP: A/V Divergence: -4.18105, Rate: -0.122975, Warpfactor: 0.989552, warpfactor_avg: 0.999992 2006-09-16 03:40:04.563 AO: Broadcasting free space avail 2006-09-16 03:40:04.563 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.564 AO: Broadcasting free space avail 2006-09-16 03:40:04.567 NVP: Video is 4.18105 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.572 NVP: A/V Divergence: -4.16703, Rate: 0.014025, Warpfactor: 0.989057, warpfactor_avg: 0.999992 2006-09-16 03:40:04.576 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.577 AO: Broadcasting free space avail 2006-09-16 03:40:04.581 NVP: Video is 4.16703 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.586 NVP: A/V Divergence: -3.994, Rate: 0.173025, Warpfactor: 0.988562, warpfactor_avg: 0.999991 2006-09-16 03:40:04.588 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.589 AO: Broadcasting free space avail 2006-09-16 03:40:04.594 NVP: Video is 3.994 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.601 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.602 AO: Broadcasting free space avail 2006-09-16 03:40:04.599 NVP: A/V Divergence: -3.6955, Rate: 0.2985, Warpfactor: 0.988068, warpfactor_avg: 0.99999 2006-09-16 03:40:04.611 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.611 AO: Broadcasting free space avail 2006-09-16 03:40:04.625 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.625 AO: Broadcasting free space avail 2006-09-16 03:40:04.630 NVP: Video is 3.6955 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.632 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:04.632 AO: Broadcasting free space avail 2006-09-16 03:40:04.636 AFD: video timecode 172838 172838 1920 1980 2006-09-16 03:40:04.637 AFD: audio timecode 178209 178209 1980 2004 2006-09-16 03:40:04.638 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1980 2006-09-16 03:40:04.647 NVP: A/V Divergence: -3.44662, Rate: 0.248875, Warpfactor: 0.987574, warpfactor_avg: 0.999989 2006-09-16 03:40:04.648 AFD: audio timecode 180369 180369 2004 2028 2006-09-16 03:40:04.648 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=2004 2006-09-16 03:40:04.651 AO: Broadcasting free space avail 2006-09-16 03:40:04.655 AO: Broadcasting free space avail 2006-09-16 03:40:04.656 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.656 AO: Broadcasting free space avail 2006-09-16 03:40:04.657 NVP: Video is 3.44662 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.666 NVP: A/V Divergence: -3.1412, Rate: 0.305425, Warpfactor: 0.98708, warpfactor_avg: 0.999988 2006-09-16 03:40:04.668 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.669 AO: Broadcasting free space avail 2006-09-16 03:40:04.670 NVP: Video is 3.1412 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:40:04.671 NVP: prebuffering pause 2006-09-16 03:40:04.671 NVP: Waiting for prebuffer.. 0 AAAAAAAADAALDALA 2006-09-16 03:40:04.688 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:04.689 AO: 63776 bytes free on soundcard 2006-09-16 03:40:04.699 AO: 61600 bytes free on soundcard 2006-09-16 03:40:04.699 AFD: video timecode 187238 176438 1960 2028 2006-09-16 03:40:04.701 AFD: audio timecode 182529 182529 2028 2052 2006-09-16 03:40:04.701 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2028 2006-09-16 03:40:04.703 AFD: audio timecode 184689 184689 2052 2076 2006-09-16 03:40:04.703 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=2052 2006-09-16 03:40:04.713 AO: 60352 bytes free on soundcard 2006-09-16 03:40:04.723 AO: 62272 bytes free on soundcard 2006-09-16 03:40:04.739 NVP: A/V Divergence: -2.7434, Rate: 0.3978, Warpfactor: 0.986587, warpfactor_avg: 0.999987 2006-09-16 03:40:04.732 AFD: video timecode 180038 180038 2000 2076 2006-09-16 03:40:04.744 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:04.745 AO: Broadcasting free space avail 2006-09-16 03:40:04.749 AO: Broadcasting free space avail 2006-09-16 03:40:04.749 AO: Broadcasting free space avail 2006-09-16 03:40:04.749 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:04.750 AO: Broadcasting free space avail 2006-09-16 03:40:04.752 AFD: audio timecode 186849 186849 2076 2100 2006-09-16 03:40:04.753 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2076 2006-09-16 03:40:04.761 AO: Broadcasting free space avail 2006-09-16 03:40:04.761 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.761 AO: Broadcasting free space avail 2006-09-16 03:40:04.764 NVP: A/V Divergence: -2.33255, Rate: 0.41085, Warpfactor: 0.986093, warpfactor_avg: 0.999987 2006-09-16 03:40:04.777 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.778 AO: Broadcasting free space avail 2006-09-16 03:40:04.787 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:04.787 AO: Broadcasting free space avail 2006-09-16 03:40:04.792 AFD: video timecode 183638 183638 2040 2100 2006-09-16 03:40:04.794 AFD: audio timecode 189009 189009 2100 2124 2006-09-16 03:40:04.794 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=2100 2006-09-16 03:40:04.795 AFD: audio timecode 191169 191169 2124 2148 2006-09-16 03:40:04.797 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=2124 2006-09-16 03:40:04.797 AO: Broadcasting free space avail 2006-09-16 03:40:04.797 AO: Broadcasting free space avail 2006-09-16 03:40:04.798 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:04.798 AO: Broadcasting free space avail 2006-09-16 03:40:04.802 NVP: A/V Divergence: -1.9744, Rate: 0.35815, Warpfactor: 0.986586, warpfactor_avg: 0.999986 2006-09-16 03:40:04.806 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:04.806 AO: Broadcasting free space avail 2006-09-16 03:40:04.807 NVP: prebuffering pause 2006-09-16 03:40:04.807 NVP: Waiting for prebuffer.. 0 AAALAAAAAADDAALA 2006-09-16 03:40:04.813 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:04.814 AO: 50944 bytes free on soundcard 2006-09-16 03:40:04.820 AO: 52128 bytes free on soundcard 2006-09-16 03:40:04.828 AO: 53632 bytes free on soundcard 2006-09-16 03:40:04.832 AFD: video timecode 198038 187238 2080 2148 2006-09-16 03:40:04.835 AFD: audio timecode 193329 193329 2148 2172 2006-09-16 03:40:04.835 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2148 2006-09-16 03:40:04.836 AFD: audio timecode 195489 195489 2172 2196 2006-09-16 03:40:04.837 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2172 2006-09-16 03:40:04.844 AO: 56736 bytes free on soundcard 2006-09-16 03:40:04.855 AO: 58848 bytes free on soundcard 2006-09-16 03:40:04.861 AFD: video timecode 190838 190838 2120 2196 2006-09-16 03:40:04.863 AFD: audio timecode 197649 197649 2196 2220 2006-09-16 03:40:04.863 AO: _AddSamples bytes=4608, used=11265, free=756735, timecode=2196 2006-09-16 03:40:04.865 AO: 60800 bytes free on soundcard 2006-09-16 03:40:04.871 NVP: A/V Divergence: -1.5683, Rate: 0.4061, Warpfactor: 0.98708, warpfactor_avg: 0.999985 2006-09-16 03:40:04.872 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:04.872 AO: Broadcasting free space avail 2006-09-16 03:40:04.873 AO: Broadcasting free space avail 2006-09-16 03:40:04.873 AO: Broadcasting free space avail 2006-09-16 03:40:04.873 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.874 AO: Broadcasting free space avail 2006-09-16 03:40:04.882 NVP: A/V Divergence: -1.45123, Rate: 0.117075, Warpfactor: 0.987573, warpfactor_avg: 0.999984 2006-09-16 03:40:04.886 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.887 AO: Broadcasting free space avail 2006-09-16 03:40:04.897 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.898 AO: Broadcasting free space avail 2006-09-16 03:40:04.909 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:04.910 AO: Broadcasting free space avail 2006-09-16 03:40:04.916 AFD: video timecode 194438 194438 2160 2220 2006-09-16 03:40:04.919 AFD: audio timecode 199809 199809 2220 2244 2006-09-16 03:40:04.927 NVP: A/V Divergence: -1.33215, Rate: 0.119075, Warpfactor: 0.988067, warpfactor_avg: 0.999983 2006-09-16 03:40:04.920 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2220 2006-09-16 03:40:04.929 AFD: audio timecode 201969 201969 2244 2268 2006-09-16 03:40:04.929 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=2244 2006-09-16 03:40:04.935 AO: Broadcasting free space avail 2006-09-16 03:40:04.935 AO: Broadcasting free space avail 2006-09-16 03:40:04.935 AO: Broadcasting free space avail 2006-09-16 03:40:04.938 NVP: prebuffering pause 2006-09-16 03:40:04.939 NVP: Waiting for prebuffer.. 0 AAALAALAAAAAADDA 2006-09-16 03:40:04.936 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:04.939 AO: Broadcasting free space avail 2006-09-16 03:40:04.946 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:04.947 AO: 51936 bytes free on soundcard 2006-09-16 03:40:04.952 AO: 52896 bytes free on soundcard 2006-09-16 03:40:04.961 AO: 54656 bytes free on soundcard 2006-09-16 03:40:04.968 AO: 55968 bytes free on soundcard 2006-09-16 03:40:04.972 AFD: video timecode 208837 198038 2200 2268 2006-09-16 03:40:04.974 AFD: audio timecode 204129 204129 2268 2292 2006-09-16 03:40:04.974 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2268 2006-09-16 03:40:04.982 AFD: audio timecode 206289 206289 2292 2316 2006-09-16 03:40:04.983 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=2292 2006-09-16 03:40:04.988 AO: 59808 bytes free on soundcard 2006-09-16 03:40:05.004 AO: 62912 bytes free on soundcard 2006-09-16 03:40:05.008 AFD: video timecode 201638 201638 2240 2316 2006-09-16 03:40:05.009 AFD: audio timecode 208449 208449 2316 2340 2006-09-16 03:40:05.010 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=2316 2006-09-16 03:40:05.019 AO: 61632 bytes free on soundcard 2006-09-16 03:40:05.021 NVP: A/V Divergence: -1.1616, Rate: 0.17055, Warpfactor: 0.988561, warpfactor_avg: 0.999982 2006-09-16 03:40:05.032 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:05.033 AO: Broadcasting free space avail 2006-09-16 03:40:05.033 AO: Broadcasting free space avail 2006-09-16 03:40:05.033 AO: Broadcasting free space avail 2006-09-16 03:40:05.034 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.034 AO: Broadcasting free space avail 2006-09-16 03:40:05.046 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.047 AO: Broadcasting free space avail 2006-09-16 03:40:05.056 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.057 AO: Broadcasting free space avail 2006-09-16 03:40:05.058 AFD: video timecode 205238 205238 2280 2340 2006-09-16 03:40:05.059 AFD: audio timecode 210609 210609 2340 2364 2006-09-16 03:40:05.060 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2340 2006-09-16 03:40:05.067 AFD: audio timecode 212769 212769 2364 2388 2006-09-16 03:40:05.068 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2364 2006-09-16 03:40:05.076 AO: Broadcasting free space avail 2006-09-16 03:40:05.077 AO: Broadcasting free space avail 2006-09-16 03:40:05.077 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:05.077 AO: Broadcasting free space avail 2006-09-16 03:40:05.089 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:05.089 AO: Broadcasting free space avail 2006-09-16 03:40:05.092 AFD: video timecode 219637 208837 2320 2388 2006-09-16 03:40:05.094 AFD: audio timecode 214929 214929 2388 2412 2006-09-16 03:40:05.094 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2388 2006-09-16 03:40:05.096 AFD: audio timecode 217089 217089 2412 2436 2006-09-16 03:40:05.096 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=2412 2006-09-16 03:40:05.101 NVP: A/V Divergence: -1.46495, Rate: -0.30335, Warpfactor: 0.988067, warpfactor_avg: 0.999982 2006-09-16 03:40:05.102 AO: Broadcasting free space avail 2006-09-16 03:40:05.102 AO: Broadcasting free space avail 2006-09-16 03:40:05.103 AO: Broadcasting free space avail 2006-09-16 03:40:05.103 AO: Broadcasting free space avail 2006-09-16 03:40:05.110 NVP: A/V Divergence: -1.5362, Rate: -0.07125, Warpfactor: 0.987573, warpfactor_avg: 0.999981 2006-09-16 03:40:05.118 AO: Broadcasting free space avail 2006-09-16 03:40:05.138 AO: Broadcasting free space avail 2006-09-16 03:40:05.144 AFD: video timecode 212437 212437 2360 2436 2006-09-16 03:40:05.149 AO: Broadcasting free space avail 2006-09-16 03:40:05.153 NVP: A/V Divergence: -1.5709, Rate: -0.0347, Warpfactor: 0.987136, warpfactor_avg: 0.99998 2006-09-16 03:40:05.155 AFD: audio timecode 219249 219249 2436 2460 2006-09-16 03:40:05.156 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2436 2006-09-16 03:40:05.159 AO: Broadcasting free space avail 2006-09-16 03:40:05.160 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:05.160 AO: Broadcasting free space avail 2006-09-16 03:40:05.170 NVP: A/V Divergence: -1.45942, Rate: 0.111475, Warpfactor: 0.98763, warpfactor_avg: 0.999979 2006-09-16 03:40:05.174 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:05.174 AO: Broadcasting free space avail 2006-09-16 03:40:05.176 NVP: prebuffering pause 2006-09-16 03:40:05.177 NVP: Waiting for prebuffer.. 0 AAAALDdAALAAAAAA 2006-09-16 03:40:05.188 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:05.189 AO: 53280 bytes free on soundcard 2006-09-16 03:40:05.196 AO: 54816 bytes free on soundcard 2006-09-16 03:40:05.203 AFD: video timecode 216037 216037 2400 2460 2006-09-16 03:40:05.205 AFD: audio timecode 221409 221409 2460 2484 2006-09-16 03:40:05.205 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2460 2006-09-16 03:40:05.206 AFD: audio timecode 223569 223569 2484 2508 2006-09-16 03:40:05.207 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=2484 2006-09-16 03:40:05.208 AO: 57024 bytes free on soundcard 2006-09-16 03:40:05.224 AO: 60064 bytes free on soundcard 2006-09-16 03:40:05.236 AFD: video timecode 230437 219637 2440 2508 2006-09-16 03:40:05.240 AFD: audio timecode 225729 225729 2508 2532 2006-09-16 03:40:05.240 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=2508 2006-09-16 03:40:05.242 AFD: audio timecode 227889 227889 2532 2556 2006-09-16 03:40:05.242 AO: _AddSamples bytes=4608, used=14337, free=753663, timecode=2532 2006-09-16 03:40:05.233 AO: 61920 bytes free on soundcard 2006-09-16 03:40:05.250 NVP: A/V Divergence: -1.21955, Rate: 0.239875, Warpfactor: 0.988124, warpfactor_avg: 0.999978 2006-09-16 03:40:05.258 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:05.259 AO: Broadcasting free space avail 2006-09-16 03:40:05.259 AO: Broadcasting free space avail 2006-09-16 03:40:05.259 AO: Broadcasting free space avail 2006-09-16 03:40:05.260 AO: Broadcasting free space avail 2006-09-16 03:40:05.260 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.260 AO: Broadcasting free space avail 2006-09-16 03:40:05.274 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.274 AO: Broadcasting free space avail 2006-09-16 03:40:05.286 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.286 AO: Broadcasting free space avail 2006-09-16 03:40:05.292 AFD: video timecode 223237 223237 2480 2556 2006-09-16 03:40:05.294 AFD: audio timecode 230049 230049 2556 2580 2006-09-16 03:40:05.294 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2556 2006-09-16 03:40:05.296 AO: Broadcasting free space avail 2006-09-16 03:40:05.296 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:05.297 AO: Broadcasting free space avail 2006-09-16 03:40:05.301 NVP: A/V Divergence: -1.20215, Rate: 0.0174, Warpfactor: 0.988618, warpfactor_avg: 0.999978 2006-09-16 03:40:05.308 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:05.309 AO: Broadcasting free space avail 2006-09-16 03:40:05.319 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:05.319 AO: Broadcasting free space avail 2006-09-16 03:40:05.324 AFD: video timecode 226837 226837 2520 2580 2006-09-16 03:40:05.329 AFD: audio timecode 232208 232208 2580 2604 2006-09-16 03:40:05.330 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2580 2006-09-16 03:40:05.331 AFD: audio timecode 234368 234368 2604 2628 2006-09-16 03:40:05.345 NVP: A/V Divergence: -1.3016, Rate: -0.09945, Warpfactor: 0.988769, warpfactor_avg: 0.999977 2006-09-16 03:40:05.332 AO: Broadcasting free space avail 2006-09-16 03:40:05.350 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.350 AO: Broadcasting free space avail 2006-09-16 03:40:05.353 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2604 2006-09-16 03:40:05.361 AO: Broadcasting free space avail 2006-09-16 03:40:05.361 AO: Broadcasting free space avail 2006-09-16 03:40:05.362 AO: Broadcasting free space avail 2006-09-16 03:40:05.377 AO: Broadcasting free space avail 2006-09-16 03:40:05.386 AO: Broadcasting free space avail 2006-09-16 03:40:05.389 AFD: video timecode 241237 230437 2560 2628 2006-09-16 03:40:05.390 AFD: audio timecode 236528 236528 2628 2652 2006-09-16 03:40:05.391 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2628 2006-09-16 03:40:05.394 AFD: audio timecode 238688 238688 2652 2676 2006-09-16 03:40:05.395 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=2652 2006-09-16 03:40:05.400 AO: Broadcasting free space avail 2006-09-16 03:40:05.401 AO: Broadcasting free space avail 2006-09-16 03:40:05.401 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:05.401 AO: Broadcasting free space avail 2006-09-16 03:40:05.409 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:05.410 AO: Broadcasting free space avail 2006-09-16 03:40:05.418 NVP: A/V Divergence: -1.3512, Rate: -0.0496, Warpfactor: 0.988771, warpfactor_avg: 0.999976 2006-09-16 03:40:05.419 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:05.420 AO: Broadcasting free space avail 2006-09-16 03:40:05.436 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:05.444 AO: Broadcasting free space avail 2006-09-16 03:40:05.449 AFD: video timecode 234037 234037 2600 2676 2006-09-16 03:40:05.451 AFD: audio timecode 240848 240848 2676 2700 2006-09-16 03:40:05.455 NVP: A/V Divergence: -1.54465, Rate: -0.19345, Warpfactor: 0.988276, warpfactor_avg: 0.999975 2006-09-16 03:40:05.452 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=2676 2006-09-16 03:40:05.457 AO: Broadcasting free space avail 2006-09-16 03:40:05.458 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:05.458 AO: Broadcasting free space avail 2006-09-16 03:40:05.472 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:05.473 AO: Broadcasting free space avail 2006-09-16 03:40:05.488 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:05.489 AO: Broadcasting free space avail 2006-09-16 03:40:05.492 AFD: video timecode 237637 237637 2640 2700 2006-09-16 03:40:05.494 AFD: audio timecode 243008 243008 2700 2724 2006-09-16 03:40:05.494 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2700 2006-09-16 03:40:05.501 NVP: A/V Divergence: -1.68348, Rate: -0.138825, Warpfactor: 0.987782, warpfactor_avg: 0.999974 2006-09-16 03:40:05.503 AFD: audio timecode 245168 245168 2724 2748 2006-09-16 03:40:05.504 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=2724 2006-09-16 03:40:05.508 AO: Broadcasting free space avail 2006-09-16 03:40:05.509 AO: Broadcasting free space avail 2006-09-16 03:40:05.509 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.509 AO: Broadcasting free space avail 2006-09-16 03:40:05.527 NVP: A/V Divergence: -1.86885, Rate: -0.185375, Warpfactor: 0.987288, warpfactor_avg: 0.999974 2006-09-16 03:40:05.528 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.528 AO: Broadcasting free space avail 2006-09-16 03:40:05.544 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.545 AO: Broadcasting free space avail 2006-09-16 03:40:05.553 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:40:05.554 AO: Broadcasting free space avail 2006-09-16 03:40:05.556 AFD: video timecode 252037 241237 2680 2748 2006-09-16 03:40:05.558 AFD: audio timecode 247328 247328 2748 2772 2006-09-16 03:40:05.558 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2748 2006-09-16 03:40:05.559 AFD: audio timecode 249488 249488 2772 2796 2006-09-16 03:40:05.560 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=2772 2006-09-16 03:40:05.570 AO: Broadcasting free space avail 2006-09-16 03:40:05.571 AO: Broadcasting free space avail 2006-09-16 03:40:05.571 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.571 AO: Broadcasting free space avail 2006-09-16 03:40:05.573 NVP: A/V Divergence: -2.00163, Rate: -0.132775, Warpfactor: 0.986794, warpfactor_avg: 0.999973 2006-09-16 03:40:05.580 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.581 AO: Broadcasting free space avail 2006-09-16 03:40:05.591 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.591 AO: Broadcasting free space avail 2006-09-16 03:40:05.596 AFD: video timecode 244837 244837 2720 2796 2006-09-16 03:40:05.599 AFD: audio timecode 251648 251648 2796 2820 2006-09-16 03:40:05.600 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2796 2006-09-16 03:40:05.602 AO: Broadcasting free space avail 2006-09-16 03:40:05.602 AO: Broadcasting free space avail 2006-09-16 03:40:05.602 AO: Broadcasting free space avail 2006-09-16 03:40:05.615 AO: Broadcasting free space avail 2006-09-16 03:40:05.629 AO: Broadcasting free space avail 2006-09-16 03:40:05.639 NVP: A/V Divergence: -1.98245, Rate: 0.019175, Warpfactor: 0.986301, warpfactor_avg: 0.999972 2006-09-16 03:40:05.643 AO: Broadcasting free space avail 2006-09-16 03:40:05.645 NVP: prebuffering pause 2006-09-16 03:40:05.645 NVP: Waiting for prebuffer.. 0 LAAAAAAAAAAAADLd 2006-09-16 03:40:05.648 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:05.648 AO: 64128 bytes free on soundcard 2006-09-16 03:40:05.659 AFD: video timecode 248437 248437 2760 2820 2006-09-16 03:40:05.661 AFD: audio timecode 253808 253808 2820 2844 2006-09-16 03:40:05.662 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2820 2006-09-16 03:40:05.663 AFD: audio timecode 255968 255968 2844 2868 2006-09-16 03:40:05.663 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=2844 2006-09-16 03:40:05.664 AO: 63072 bytes free on soundcard 2006-09-16 03:40:05.680 AO: 61984 bytes free on soundcard 2006-09-16 03:40:05.695 AO: 60828 bytes free on soundcard 2006-09-16 03:40:05.696 AFD: video timecode 262837 252037 2800 2868 2006-09-16 03:40:05.704 AFD: audio timecode 258128 258128 2868 2892 2006-09-16 03:40:05.705 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=2868 2006-09-16 03:40:05.709 AFD: audio timecode 260288 260288 2892 2916 2006-09-16 03:40:05.709 AO: _AddSamples bytes=4608, used=13825, free=754175, timecode=2892 2006-09-16 03:40:05.711 AO: 63872 bytes free on soundcard 2006-09-16 03:40:05.713 NVP: A/V Divergence: -2.04308, Rate: -0.060625, Warpfactor: 0.985808, warpfactor_avg: 0.999971 2006-09-16 03:40:05.720 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:05.721 AO: Broadcasting free space avail 2006-09-16 03:40:05.721 AO: Broadcasting free space avail 2006-09-16 03:40:05.721 AO: Broadcasting free space avail 2006-09-16 03:40:05.722 AO: Broadcasting free space avail 2006-09-16 03:40:05.722 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.722 AO: Broadcasting free space avail 2006-09-16 03:40:05.741 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.742 AO: Broadcasting free space avail 2006-09-16 03:40:05.747 NVP: A/V Divergence: -1.78855, Rate: 0.254525, Warpfactor: 0.986301, warpfactor_avg: 0.99997 2006-09-16 03:40:05.750 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.750 AO: Broadcasting free space avail 2006-09-16 03:40:05.751 NVP: prebuffering pause 2006-09-16 03:40:05.751 NVP: Waiting for prebuffer.. 0 dLALAAAAAAAAAAAA 2006-09-16 03:40:05.764 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:05.765 AO: 53664 bytes free on soundcard 2006-09-16 03:40:05.772 AO: 55072 bytes free on soundcard 2006-09-16 03:40:05.772 AFD: video timecode 255637 255637 2840 2916 2006-09-16 03:40:05.774 AFD: audio timecode 262448 262448 2916 2940 2006-09-16 03:40:05.774 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2916 2006-09-16 03:40:05.788 AO: 58208 bytes free on soundcard 2006-09-16 03:40:05.799 AO: 60352 bytes free on soundcard 2006-09-16 03:40:05.803 AFD: video timecode 259237 259237 2880 2940 2006-09-16 03:40:05.806 AFD: audio timecode 264608 264608 2940 2964 2006-09-16 03:40:05.807 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2940 2006-09-16 03:40:05.811 NVP: A/V Divergence: -1.5289, Rate: 0.25965, Warpfactor: 0.986794, warpfactor_avg: 0.999969 2006-09-16 03:40:05.813 AFD: audio timecode 266768 266768 2964 2988 2006-09-16 03:40:05.813 AO: _AddSamples bytes=4608, used=11265, free=756735, timecode=2964 2006-09-16 03:40:05.819 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:05.825 AO: Broadcasting free space avail 2006-09-16 03:40:05.825 AO: Broadcasting free space avail 2006-09-16 03:40:05.826 AO: Broadcasting free space avail 2006-09-16 03:40:05.826 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.826 AO: Broadcasting free space avail 2006-09-16 03:40:05.844 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.845 AO: Broadcasting free space avail 2006-09-16 03:40:05.855 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:05.856 AO: Broadcasting free space avail 2006-09-16 03:40:05.860 AFD: video timecode 273637 262837 2920 2988 2006-09-16 03:40:05.862 AFD: audio timecode 268928 268928 2988 3012 2006-09-16 03:40:05.862 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2988 2006-09-16 03:40:05.871 NVP: A/V Divergence: -1.55292, Rate: -0.024025, Warpfactor: 0.987287, warpfactor_avg: 0.999968 2006-09-16 03:40:05.875 AFD: audio timecode 271088 271088 3012 3036 2006-09-16 03:40:05.872 AO: Broadcasting free space avail 2006-09-16 03:40:05.877 AO: Broadcasting free space avail 2006-09-16 03:40:05.877 AO: Broadcasting free space avail 2006-09-16 03:40:05.880 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=3012 2006-09-16 03:40:05.894 AO: Broadcasting free space avail 2006-09-16 03:40:05.895 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:05.895 AO: Broadcasting free space avail 2006-09-16 03:40:05.907 NVP: A/V Divergence: -1.65217, Rate: -0.09925, Warpfactor: 0.986794, warpfactor_avg: 0.999967 2006-09-16 03:40:05.910 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:05.910 AO: Broadcasting free space avail 2006-09-16 03:40:05.912 NVP: prebuffering pause 2006-09-16 03:40:05.912 NVP: Waiting for prebuffer.. 0 AdAALLAAAAAAAAAA 2006-09-16 03:40:05.916 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:05.916 AO: 58240 bytes free on soundcard 2006-09-16 03:40:05.924 AO: 59680 bytes free on soundcard 2006-09-16 03:40:05.932 AO: 61248 bytes free on soundcard 2006-09-16 03:40:05.933 AFD: video timecode 266437 266437 2960 3036 2006-09-16 03:40:05.935 AFD: audio timecode 273248 273248 3036 3060 2006-09-16 03:40:05.935 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3036 2006-09-16 03:40:05.948 AO: 64320 bytes free on soundcard 2006-09-16 03:40:05.966 AO: 63744 bytes free on soundcard 2006-09-16 03:40:05.967 AFD: video timecode 270037 270037 3000 3060 2006-09-16 03:40:05.971 AFD: audio timecode 275408 275408 3060 3084 2006-09-16 03:40:05.972 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=3060 2006-09-16 03:40:05.975 AFD: audio timecode 277568 277568 3084 3108 2006-09-16 03:40:05.975 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=3084 2006-09-16 03:40:05.976 AO: 61504 bytes free on soundcard 2006-09-16 03:40:05.982 NVP: A/V Divergence: -1.67663, Rate: -0.02445, Warpfactor: 0.986359, warpfactor_avg: 0.999966 2006-09-16 03:40:05.990 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:05.991 AO: Broadcasting free space avail 2006-09-16 03:40:05.991 AO: Broadcasting free space avail 2006-09-16 03:40:05.991 AO: Broadcasting free space avail 2006-09-16 03:40:05.992 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:05.992 AO: Broadcasting free space avail 2006-09-16 03:40:06.010 NVP: A/V Divergence: -1.5512, Rate: 0.125425, Warpfactor: 0.986852, warpfactor_avg: 0.999966 2006-09-16 03:40:06.011 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:40:06.011 AO: Broadcasting free space avail 2006-09-16 03:40:06.013 NVP: prebuffering pause 2006-09-16 03:40:06.013 NVP: Waiting for prebuffer.. 0 ADAAALDAAAAALAAA 2006-09-16 03:40:06.020 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:06.020 AO: 53632 bytes free on soundcard 2006-09-16 03:40:06.030 AO: 55488 bytes free on soundcard 2006-09-16 03:40:06.030 AFD: video timecode 284437 273637 3040 3108 2006-09-16 03:40:06.032 AFD: audio timecode 279728 279728 3108 3132 2006-09-16 03:40:06.032 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3108 2006-09-16 03:40:06.034 AFD: audio timecode 281888 281888 3132 3156 2006-09-16 03:40:06.034 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=3132 2006-09-16 03:40:06.044 AO: 58176 bytes free on soundcard 2006-09-16 03:40:06.059 AO: 61088 bytes free on soundcard 2006-09-16 03:40:06.060 AFD: video timecode 277237 277237 3080 3156 2006-09-16 03:40:06.063 AFD: audio timecode 284048 284048 3156 3180 2006-09-16 03:40:06.064 AO: _AddSamples bytes=4608, used=11265, free=756735, timecode=3156 2006-09-16 03:40:06.070 NVP: A/V Divergence: -1.33215, Rate: 0.21905, Warpfactor: 0.987346, warpfactor_avg: 0.999965 2006-09-16 03:40:06.072 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:06.082 AO: Broadcasting free space avail 2006-09-16 03:40:06.083 AO: Broadcasting free space avail 2006-09-16 03:40:06.083 AO: Broadcasting free space avail 2006-09-16 03:40:06.083 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:06.083 AO: Broadcasting free space avail 2006-09-16 03:40:06.091 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:06.091 AO: Broadcasting free space avail 2006-09-16 03:40:06.102 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:06.102 AO: Broadcasting free space avail 2006-09-16 03:40:06.112 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:06.113 AO: Broadcasting free space avail 2006-09-16 03:40:06.120 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:06.120 AO: Broadcasting free space avail 2006-09-16 03:40:06.121 AFD: video timecode 280837 280837 3120 3180 2006-09-16 03:40:06.124 AFD: audio timecode 286208 286208 3180 3204 2006-09-16 03:40:06.125 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3180 2006-09-16 03:40:06.126 AFD: audio timecode 288368 288368 3204 3228 2006-09-16 03:40:06.127 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3204 2006-09-16 03:40:06.131 NVP: A/V Divergence: -1.4241, Rate: -0.09195, Warpfactor: 0.987837, warpfactor_avg: 0.999964 2006-09-16 03:40:06.137 AO: Broadcasting free space avail 2006-09-16 03:40:06.138 AO: Broadcasting free space avail 2006-09-16 03:40:06.138 AO: Broadcasting free space avail 2006-09-16 03:40:06.138 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:06.139 AO: Broadcasting free space avail 2006-09-16 03:40:06.153 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:06.153 AO: Broadcasting free space avail 2006-09-16 03:40:06.167 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:40:06.168 AO: Broadcasting free space avail 2006-09-16 03:40:06.172 AFD: video timecode 295237 284437 3160 3228 2006-09-16 03:40:06.175 AFD: audio timecode 290528 290528 3228 3252 2006-09-16 03:40:06.175 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3228 2006-09-16 03:40:06.176 AO: Broadcasting free space avail 2006-09-16 03:40:06.176 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:40:06.177 AO: Broadcasting free space avail 2006-09-16 03:40:06.182 NVP: A/V Divergence: -1.53682, Rate: -0.112725, Warpfactor: 0.987343, warpfactor_avg: 0.999963 2006-09-16 03:40:06.184 AFD: audio timecode 292688 292688 3252 3276 2006-09-16 03:40:06.185 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3252 2006-09-16 03:40:06.192 AO: Broadcasting free space avail 2006-09-16 03:40:06.199 NVP: A/V Divergence: -1.72135, Rate: -0.184525, Warpfactor: 0.986849, warpfactor_avg: 0.999962 2006-09-16 03:40:06.195 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:40:06.202 AO: Broadcasting free space avail 2006-09-16 03:40:06.203 NVP: prebuffering pause 2006-09-16 03:40:06.204 NVP: Waiting for prebuffer.. 0 AAAAAAAAAALLdAAA 2006-09-16 03:40:06.217 AO: OutputAudioLoop: audio paused 2006-09-16 03:40:06.218 AO: 58752 bytes free on soundcard 2006-09-16 03:40:06.224 AO: 59968 bytes free on soundcard 2006-09-16 03:40:06.230 AFD: video timecode 288037 288037 3200 3276 2006-09-16 03:40:06.232 AFD: audio timecode 294848 294848 3276 3300 2006-09-16 03:40:06.233 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3276 2006-09-16 03:40:06.240 AO: 63072 bytes free on soundcard 2006-09-16 03:40:06.248 AO: 60512 bytes free on soundcard 2006-09-16 03:40:06.252 AFD: video timecode 291637 291637 3240 3300 2006-09-16 03:40:06.255 AFD: audio timecode 297008 297008 3300 3324 2006-09-16 03:40:06.260 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=3300 2006-09-16 03:40:06.261 AFD: audio timecode 299168 299168 3324 3348 2006-09-16 03:40:06.262 AO: _AddSamples bytes=4608, used=10753, free=757247, timecode=3324 2006-09-16 03:40:06.264 AO: OutputAudioLoop: Play Event 2006-09-16 03:40:06.265 AO: Broadcasting free space avail 2006-09-16 03:40:06.265 AO: Broadcasting free space avail 2006-09-16 03:40:06.265 AO: Broadcasting free space avail 2006-09-16 03:40:06.265 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:06.266 AO: Broadcasting free space avail 2006-09-16 03:40:06.259 NVP: A/V Divergence: -1.6535, Rate: 0.06785, Warpfactor: 0.987277, warpfactor_avg: 0.999961 2006-09-16 03:40:06.272 NVP: A/V Divergence: -1.62762, Rate: 0.025875, Warpfactor: 0.987148, warpfactor_avg: 0.99996 2006-09-16 03:40:06.273 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:06.273 AO: Broadcasting free space avail 2006-09-16 03:40:06.289 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:06.289 AO: Broadcasting free space avail 2006-09-16 03:40:06.301 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:40:06.302 AO: Broadcasting free space avail 2006-09-16 03:40:06.306 TV: Attempting to change from WatchingLiveTV to None 2006-09-16 03:40:06.306 TV: StopStuff() -- begin 2006-09-16 03:40:06.306 TV: StopStuff(): stopping ring buffer[s] 2006-09-16 03:40:06.308 AFD: video timecode 306037 295237 3280 3348 2006-09-16 03:40:06.310 AFD: audio timecode 301328 301328 3348 3372 2006-09-16 03:40:06.310 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3348 2006-09-16 03:40:06.312 AO: Broadcasting free space avail 2006-09-16 03:40:06.312 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:40:06.315 AO: Broadcasting free space avail 2006-09-16 03:40:06.316 TV: StopStuff(): stopping player[s] (1/2) 2006-09-16 03:40:06.316 TV: StopStuff(): stopping recorder[s] 2006-09-16 03:40:06.316 write -> 15 32 QUERY_RECORDER 1[]:[]STOP_LIVETV 2006-09-16 03:40:06.319 AFD: audio timecode 303487 303487 3372 3396 2006-09-16 03:40:06.320 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3372 2006-09-16 03:40:06.320 NVP: Exited decoder loop. 2006-09-16 03:40:06.326 AO: Broadcasting free space avail 2006-09-16 03:40:06.327 AO: Broadcasting free space avail 2006-09-16 03:40:06.327 AO: Broadcasting free space avail 2006-09-16 03:40:06.331 VideoOutputXv: dtor 2006-09-16 03:40:06.331 VideoOutputXv: DiscardFrames(1) 2006-09-16 03:40:06.332 VideoOutputXv: DiscardFrames() 1: AAAAAAADAAuAALAA 2006-09-16 03:40:06.333 VideoOutputXv: DiscardFrames() 2: AAAAAAADAAdAADAA 2006-09-16 03:40:06.333 VideoOutputXv: Frame c is in use by avlib and so is being held for later discarding. 2006-09-16 03:40:06.333 VideoOutputXv: Frame c is in use by avlib and so is being held for later discarding. 2006-09-16 03:40:06.334 VideoOutputXv: DiscardFrames() 3: AAAAAAADAAdAAAAA -- done() 2006-09-16 03:40:06.334 VideoOutputXv: DiscardFrames(1) 2006-09-16 03:40:06.334 VideoOutputXv: DiscardFrames() 1: AAAAAAADAAdAAAAA 2006-09-16 03:40:06.335 VideoOutputXv: Frame c is in use by avlib and so is being held for later discarding. 2006-09-16 03:40:06.335 VideoOutputXv: DiscardFrames() 2: AAAAAAADAAdAAAAA 2006-09-16 03:40:06.335 VideoOutputXv: Frame c is in use by avlib and so is being held for later discarding. 2006-09-16 03:40:06.335 VideoOutputXv: DiscardFrames() 3: AAAAAAADAAdAAAAA -- done() 2006-09-16 03:40:06.336 AO: Broadcasting free space avail 2006-09-16 03:40:06.344 AO: Broadcasting free space avail 2006-09-16 03:40:06.349 VideoOutputXv: Closing XVideo port 68 2006-09-16 03:40:06.353 AO: Broadcasting free space avail 2006-09-16 03:40:06.356 AO: Killing AudioOutputDSP 2006-09-16 03:40:06.360 AO: OutputAudioLoop: Stop Event 2006-09-16 03:40:06.360 AO: kickoffOutputAudioLoop exiting 2006-09-16 03:40:06.383 MythSocket(84bd718:11): socket is readable 2006-09-16 03:40:06.384 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:40:06.384 read <- 11 48 BACKEND_MESSAGE[]:[]DONE_RECORDING 1 7[]:[]empty 2006-09-16 03:40:06.472 MythSocket(84bd718:11): socket is readable 2006-09-16 03:40:06.472 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:40:06.473 read <- 11 78 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr-2006-09-16T03:39... 2006-09-16 03:40:06.481 MythSocket(84bd718:11): socket is readable 2006-09-16 03:40:06.482 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:40:06.482 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:40:06.485 read <- 15 2 ok 2006-09-16 03:40:06.485 TV: StopStuff(): stopping player[s] (2/2) 2006-09-16 03:40:06.489 write -> 12 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2006-09-16 03:40:06.491 read <- 12 2 OK 2006-09-16 03:40:06.595 MythSocket(85375c8:15): DownRef: -1 2006-09-16 03:40:06.595 MythSocket(85375c8:15): state change Connected -> Idle 2006-09-16 03:40:06.596 MythSocket(85375c8:-1): delete socket 2006-09-16 03:40:06.537 MythSocket(84bd718:11): socket is readable 2006-09-16 03:40:06.597 MythSocket(84bd718:11): cb->readyRead() 2006-09-16 03:40:06.598 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:40:06.608 TV: StopStuff() -- end 2006-09-16 03:40:06.609 TV: Changing from WatchingLiveTV to None 2006-09-16 03:40:06.618 DPMS Reactivated. 2006-09-16 03:40:08.117 write -> 12 31 QUERY_IS_ACTIVE_BACKEND[]:[]pvr 2006-09-16 03:40:08.119 read <- 12 4 TRUE 2006-09-16 03:40:09.677 MythSocket(8540c48:12): DownRef: -1 2006-09-16 03:40:09.678 MythSocket(8540c48:12): state change Connected -> Idle 2006-09-16 03:40:09.678 MythSocket(8540c48:-1): delete socket 2006-09-16 03:40:09.679 MythSocket(84bd718:11): DownRef: 0 2006-09-16 03:40:09.685 MythSocket(84bd718:11): DownRef: -1 2006-09-16 03:40:09.685 MythSocket(84bd718:11): state change Connected -> Idle 2006-09-16 03:40:09.686 MythSocket(84bd718:-1): delete socket 2006-09-16 03:40:09.858 MythSocket: readyread thread exit