2006-09-16 03:35:36.729 Using runtime prefix = /usr/local 2006-09-16 03:35:36.763 DPMS is active. 2006-09-16 03:35:36.822 New DB connection, total: 1 2006-09-16 03:35:36.851 Connected to database 'mythconverg' at host: localhost 2006-09-16 03:35:36.858 Total desktop dim: 720x576, with 1 screen[s]. 2006-09-16 03:35:36.867 Using screen 0, 720x576 at 0,0 2006-09-16 03:35:37.124 user: 1000 effective user: 1000 before privileged thread 2006-09-16 03:35:37.126 user: 1000 effective user: 1000 after privileged thread 2006-09-16 03:35:37.130 Current Schema Version: 1160 2006-09-16 03:35:37.132 mythfrontend version: 0.20.20060828-3 www.mythtv.org 2006-09-16 03:35:37.133 Enabled verbose msgs: all nodatabase 2006-09-16 03:35:37.163 user: 1000 effective user: 1000 run_priv_thread 2006-09-16 03:35:37.804 max_width: 720 max_height: 576 2006-09-16 03:35:38.154 Total desktop dim: 720x576, with 1 screen[s]. 2006-09-16 03:35:38.158 Using screen 0, 720x576 at 0,0 2006-09-16 03:35:38.160 Switching to square mode (Titivillus) __driCreateNewScreen_20050727 - succeeded 2006-09-16 03:35:38.301 Using the Qt painter 2006-09-16 03:35:39.327 /home/mythtv/.mythtv/joystickmenurc not found. 2006-09-16 03:35:39.328 Joystick disabled. 2006-09-16 03:35:39.330 New DB connection, total: 2 2006-09-16 03:35:39.332 Connected to database 'mythconverg' at host: localhost 2006-09-16 03:35:39.667 Loading from: /usr/local/share/mythtv/themes/default/base.xml 2006-09-16 03:35:41.405 Registering Internal as a media playback plugin. 2006-09-16 03:35:41.535 Registering MythDVD DVD Media Handler as a media handler ext() 2006-09-16 03:35:41.539 Registering MythDVD VCD Media Handler as a media handler ext() 2006-09-16 03:35:41.934 generating cache image for: /usr/local/share/mythtv/themes/default/blank.png 2006-09-16 03:35:54.630 MythSocket(852b5e8:11): new socket 2006-09-16 03:35:54.641 Connecting to backend server: 192.168.178.31:6543 (try 1 of 5) 2006-09-16 03:35:54.642 MythSocket(84feee8:12): new socket 2006-09-16 03:35:54.642 MythSocket(84feee8:12): attempting connect() to (192.168.178.31:6543) 2006-09-16 03:35:54.643 MythSocket(84feee8:12): state change Idle -> Connected 2006-09-16 03:35:54.644 write -> 12 21 MYTH_PROTO_VERSION 30 2006-09-16 03:35:54.647 read <- 12 13 ACCEPT[]:[]30 2006-09-16 03:35:54.647 Using protocol version 30 2006-09-16 03:35:54.647 write -> 12 17 ANN Monitor pvr 0 2006-09-16 03:35:54.649 read <- 12 2 OK 2006-09-16 03:35:54.649 MythSocket(852b5e8:11): attempting connect() to (192.168.178.31:6543) 2006-09-16 03:35:54.650 MythSocket(852b5e8:11): state change Idle -> Connected 2006-09-16 03:35:54.650 write -> 11 17 ANN Monitor pvr 1 2006-09-16 03:35:54.655 read <- 11 2 OK 2006-09-16 03:35:54.656 MythSocket(852b5e8:11): UpRef: 1 2006-09-16 03:35:54.656 write -> 12 29 GET_NEXT_FREE_RECORDER[]:[]-1 2006-09-16 03:35:54.657 MythSocket: readyread thread start 2006-09-16 03:35:54.658 read <- 12 29 1[]:[]192.168.178.31[]:[]6543 2006-09-16 03:35:54.660 TV: Attempting to change from None to WatchingLiveTV 2006-09-16 03:35:54.661 MythSocket(85392f8:15): new socket 2006-09-16 03:35:54.661 MythSocket(85392f8:15): attempting connect() to (192.168.178.31:6543) 2006-09-16 03:35:54.666 MythSocket(85392f8:15): state change Idle -> Connected 2006-09-16 03:35:54.667 write -> 15 21 MYTH_PROTO_VERSION 30 2006-09-16 03:35:54.671 read <- 15 13 ACCEPT[]:[]30 2006-09-16 03:35:54.672 Using protocol version 30 2006-09-16 03:35:54.672 write -> 15 18 ANN Playback pvr 0 2006-09-16 03:35:54.677 read <- 15 2 OK 2006-09-16 03:35:54.677 write -> 15 72 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-pvr-2006-09-16T03:35:5... 2006-09-16 03:35:55.215 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:35:55.215 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:35:55.216 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:35:55.243 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:35:55.244 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:35:55.244 read <- 11 78 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr-2006-09-16T03:35... 2006-09-16 03:35:55.279 read <- 15 2 ok 2006-09-16 03:35:55.284 LiveTVChain(live-pvr-2006-09-16T03:35:54): ReloadAll(): Added new recording 2006-09-16 03:35:55.295 RingBuf(/mnt/recordings/7_20060916033555.mpg): OpenFile(/mnt/recordings/7_20060916033555.mpg, 12) 2006-09-16 03:35:56.017 RingBuf(/mnt/recordings/7_20060916033555.mpg): CalcReadAheadThresh(4000 KB) -> threshhold(146 KB) min read(32 KB) blk size(64 KB) 2006-09-16 03:35:56.018 write -> 15 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2006-09-16 03:35:56.021 read <- 15 1 1 2006-09-16 03:35:56.021 write -> 15 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2006-09-16 03:35:56.029 read <- 15 1 1 2006-09-16 03:35:56.030 TV: StartRecorder(): took 12 ms to start recorder. 2006-09-16 03:35:56.030 write -> 15 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE 2006-09-16 03:35:56.032 read <- 15 2 25 2006-09-16 03:35:56.056 DPMS Deactivated 2006-09-16 03:35:56.084 write -> 12 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2006-09-16 03:35:56.095 read <- 12 2 OK 2006-09-16 03:35:56.110 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:35:56.111 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:35:56.111 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:35:56.171 TV: Output filters for this channel are: '' 2006-09-16 03:35:56.261 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 6000000 at 0x0x84ffbf0 2006-09-16 03:35:56.280 VideoOutputXv: XvMC version: 1.1 2006-09-16 03:35:56.282 XvMCSurfaceTypes::find(w 720, h 576, chroma 1, vld 1, idct 0, mpeg2, sub-width 0, sub-height 0, disp, p<= 68, 680 <=p, port, surfNum) 2006-09-16 03:35:56.282 Trying XvMC port 68 2006-09-16 03:35:56.291 Found a suitable XvMC surface 0 2006-09-16 03:35:56.292 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask 2006-09-16 03:35:56.293 VideoOutputXv: Adaptor#0: XV_SWOV has flag[s]: XvInputMask XvImageMask 2006-09-16 03:35:56.293 XvMCSurfaceTypes::find(w 720, h 576, chroma 1, vld 1, idct 0, mpeg2, sub-width 0, sub-height 0, disp, p<= 68, 680 <=p, port, surfNum) 2006-09-16 03:35:56.293 Trying XvMC port 68 2006-09-16 03:35:56.294 Found a suitable XvMC surface 0 2006-09-16 03:35:56.294 VideoOutputXv: Grabbed xv port 68 2006-09-16 03:35:56.295 VideoOutputXv: XvMC surface found with VLD support on port 68 2006-09-16 03:35:56.303 VideoOutputXv: Closing XVideo port 68 2006-09-16 03:35:56.305 detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan 2006-09-16 03:35:56.305 AFD: Looking for decoder for MPEG2VIDEO_XVMC_VLD 2006-09-16 03:35:56.305 AFD: Opened codec 0x8e88ff0, id(MPEG2VIDEO_XVMC_VLD) type(Video) 2006-09-16 03:35:56.306 AFD: Stream #1, has id 0x448 codec id MP2, type Audio, bitrate 224000 at 0x0x8549ed0 2006-09-16 03:35:56.306 AFD: Looking for decoder for MP2 2006-09-16 03:35:56.466 AFD: Opened codec 0x8e89340, id(MP2) type(Audio) 2006-09-16 03:35:56.467 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Unknown language(0). 2006-09-16 03:35:56.485 RingBuf(/mnt/recordings/7_20060916033555.mpg): CalcReadAheadThresh(6224 KB) -> threshhold(227 KB) min read(32 KB) blk size(128 KB) 2006-09-16 03:35:56.485 AFD: Selected track 1: Unknown MP2 2ch (A/V Stream #1) 2006-09-16 03:35:56.486 AFD: Initializing audio parms from audio track #1 2006-09-16 03:35:56.486 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:35:56.503 AO: Killing AudioOutputDSP 2006-09-16 03:35:56.504 Opening OSS audio device '/dev/dsp'. 2006-09-16 03:35:56.523 AO: Audio fragment size: 4096 2006-09-16 03:35:56.531 AO: Audio Stretch Factor: 1 2006-09-16 03:35:56.531 AO: Ending reconfigure 2006-09-16 03:35:56.532 Dec: Trying to select track (w/lang) 2006-09-16 03:35:56.532 Dec: Selecting first track 2006-09-16 03:35:56.533 Dec: Selected track #1 in the Unknown language(0) 2006-09-16 03:35:56.533 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0) 2006-09-16 03:35:56.541 Position map filled from DB to: 1 2006-09-16 03:35:56.541 SyncPositionMap watchingrecording, from DB: 1 entries 2006-09-16 03:35:56.542 write -> 15 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN 2006-09-16 03:35:56.543 read <- 15 8 0[]:[]12 2006-09-16 03:35:56.543 Filling position map from 2 to 1 2006-09-16 03:35:56.544 write -> 15 50 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]2[]:[]1 2006-09-16 03:35:56.545 read <- 15 2 ok 2006-09-16 03:35:56.545 Position map filled from Encoder to: 1 2006-09-16 03:35:56.545 SyncPositionMap watchingrecording total: 1 entries 2006-09-16 03:35:56.546 SyncPositionMap, new totframes: 12, new length: 0, posMap size: 1 Input #0, mpeg, from '/mnt/recordings/7_20060916033555.mpg': Duration: N/A, bitrate: N/A Stream #0.0[0x1e0], 1/90000: Video: mpegvideo_xvmc_vld, yuv420p, 720x576, 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:35:56.546 AFD: Partial position map found 2006-09-16 03:35:56.547 AFD: Successfully opened decoder for file: "/mnt/recordings/7_20060916033555.mpg". novideo(0) 2006-09-16 03:35:56.552 AO: kickoffOutputAudioLoop: pid = 8653 2006-09-16 03:35:56.553 AO: OutputAudioLoop: Play Event 2006-09-16 03:35:56.553 AO: Broadcasting free space avail 2006-09-16 03:35:56.597 AO: OutputAudioLoop: audio paused 2006-09-16 03:35:56.597 AO: 65536 bytes free on soundcard 2006-09-16 03:35:56.606 AO: 63040 bytes free on soundcard 2006-09-16 03:35:56.614 AO: 60416 bytes free on soundcard 2006-09-16 03:35:56.621 AO: 61856 bytes free on soundcard 2006-09-16 03:35:56.630 AO: 59392 bytes free on soundcard 2006-09-16 03:35:56.638 AO: 61088 bytes free on soundcard 2006-09-16 03:35:56.639 VideoOutputXv: ctor 2006-09-16 03:35:56.642 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0 2006-09-16 03:35:56.642 Snapping height to avoid scaling: height: 576, top: 0 2006-09-16 03:35:56.643 Snapping width to avoid scaling: width: 720, left: 0 2006-09-16 03:35:56.643 Display Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2006-09-16 03:35:56.643 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2006-09-16 03:35:56.644 VideoOutputXv: Pixel dimensions: Screen 720x576, window 720x576 2006-09-16 03:35:56.649 AO: 63072 bytes free on soundcard 2006-09-16 03:35:56.649 VideoOutputXv: Estimated display dimensions: 244x195 mm Aspect: 1.25128 2006-09-16 03:35:56.650 VideoOutputXv: Estimated window dimensions: 244x195 mm Aspect: 1.25128 2006-09-16 03:35:56.653 VideoOutputXv: XvMCTex: Init failed 2006-09-16 03:35:56.654 VideoOutputXv: Number of bits per pixel is 16, but we only support ARGB 32 bbp for ChromaKeyOSD. 2006-09-16 03:35:56.654 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask 2006-09-16 03:35:56.655 VideoOutputXv: Adaptor#0: XV_SWOV has flag[s]: XvInputMask XvImageMask 2006-09-16 03:35:56.655 XvMCSurfaceTypes::find(w 720, h 576, chroma 1, vld 1, idct 0, mpeg2, sub-width 0, sub-height 0, disp, p<= 68, 680 <=p, port, surfNum) 2006-09-16 03:35:56.655 Trying XvMC port 68 2006-09-16 03:35:56.656 Found a suitable XvMC surface 0 2006-09-16 03:35:56.657 AO: 60608 bytes free on soundcard 2006-09-16 03:35:56.658 VideoOutputXv: Grabbed xv port 68 2006-09-16 03:35:56.659 VideoOutputXv: XvMC surface found with VLD support on port 68 2006-09-16 03:35:56.659 VideoOutputXv: XvMC Adaptor Name: 'XV_SWOV' 2006-09-16 03:35:56.666 AO: 62208 bytes free on soundcard 2006-09-16 03:35:56.673 AO: 59584 bytes free on soundcard 2006-09-16 03:35:56.683 AO: 61536 bytes free on soundcard 2006-09-16 03:35:56.689 AO: 58624 bytes free on soundcard 2006-09-16 03:35:56.699 AO: 60384 bytes free on soundcard 2006-09-16 03:35:56.694 Snapping height to avoid scaling: height: 576, top: 0 2006-09-16 03:35:56.700 Snapping width to avoid scaling: width: 720, left: 0 2006-09-16 03:35:56.700 Display Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.25128 2006-09-16 03:35:56.700 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2006-09-16 03:35:56.705 AO: 61664 bytes free on soundcard 2006-09-16 03:35:56.713 AO: 59104 bytes free on soundcard 2006-09-16 03:35:56.722 AO: 60704 bytes free on soundcard 2006-09-16 03:35:56.729 AO: 62176 bytes free on soundcard 2006-09-16 03:35:56.736 AO: 59424 bytes free on soundcard 2006-09-16 03:35:56.744 AO: 60960 bytes free on soundcard 2006-09-16 03:35:56.752 AO: 62496 bytes free on soundcard 2006-09-16 03:35:56.761 AO: 60064 bytes free on soundcard 2006-09-16 03:35:56.771 AO: 61952 bytes free on soundcard 2006-09-16 03:35:56.777 AO: 59008 bytes free on soundcard 2006-09-16 03:35:56.785 AO: 60512 bytes free on soundcard 2006-09-16 03:35:56.792 AO: 61984 bytes free on soundcard 2006-09-16 03:35:56.802 AO: 59808 bytes free on soundcard 2006-09-16 03:35:56.809 AO: 61024 bytes free on soundcard 2006-09-16 03:35:56.820 AO: 63136 bytes free on soundcard 2006-09-16 03:35:56.824 AO: 59936 bytes free on soundcard 2006-09-16 03:35:56.836 AO: 62112 bytes free on soundcard 2006-09-16 03:35:56.840 AO: 58912 bytes free on soundcard 2006-09-16 03:35:56.848 AO: 60448 bytes free on soundcard 2006-09-16 03:35:56.856 AO: 62016 bytes free on soundcard 2006-09-16 03:35:56.864 AO: 59424 bytes free on soundcard 2006-09-16 03:35:56.873 AO: 61056 bytes free on soundcard 2006-09-16 03:35:56.880 AO: 62528 bytes free on soundcard 2006-09-16 03:35:56.888 AO: 59936 bytes free on soundcard 2006-09-16 03:35:56.896 AO: 61504 bytes free on soundcard 2006-09-16 03:35:56.906 AO: 59264 bytes free on soundcard 2006-09-16 03:35:56.913 AO: 60512 bytes free on soundcard 2006-09-16 03:35:56.924 AO: 62720 bytes free on soundcard 2006-09-16 03:35:56.932 AO: 60192 bytes free on soundcard 2006-09-16 03:35:56.941 AO: 61792 bytes free on soundcard 2006-09-16 03:35:56.948 AO: 59168 bytes free on soundcard 2006-09-16 03:35:56.959 AO: 61312 bytes free on soundcard 2006-09-16 03:35:56.965 AO: 62304 bytes free on soundcard 2006-09-16 03:35:56.975 AO: 60288 bytes free on soundcard 2006-09-16 03:35:56.980 AO: 61248 bytes free on soundcard 2006-09-16 03:35:56.994 AO: 63776 bytes free on soundcard 2006-09-16 03:35:57.000 AO: 60960 bytes free on soundcard 2006-09-16 03:35:57.010 AO: 62784 bytes free on soundcard 2006-09-16 03:35:57.017 AO: 60000 bytes free on soundcard 2006-09-16 03:35:57.029 AO: 62336 bytes free on soundcard 2006-09-16 03:35:57.036 AO: 59680 bytes free on soundcard 2006-09-16 03:35:57.046 AO: 61472 bytes free on soundcard 2006-09-16 03:35:57.052 AO: 58656 bytes free on soundcard 2006-09-16 03:35:57.061 AO: 60384 bytes free on soundcard 2006-09-16 03:35:57.068 AO: 61760 bytes free on soundcard 2006-09-16 03:35:57.077 AO: 59360 bytes free on soundcard 2006-09-16 03:35:57.086 AO: 60992 bytes free on soundcard 2006-09-16 03:35:57.096 AO: 62944 bytes free on soundcard 2006-09-16 03:35:57.104 AO: 60480 bytes free on soundcard 2006-09-16 03:35:57.112 AO: 62016 bytes free on soundcard 2006-09-16 03:35:57.121 AO: 59488 bytes free on soundcard 2006-09-16 03:35:57.128 AO: 60992 bytes free on soundcard 2006-09-16 03:35:57.136 AO: 62528 bytes free on soundcard 2006-09-16 03:35:57.144 AO: 59968 bytes free on soundcard 2006-09-16 03:35:57.155 AO: 62080 bytes free on soundcard 2006-09-16 03:35:57.160 AO: 58944 bytes free on soundcard 2006-09-16 03:35:57.168 AO: 60480 bytes free on soundcard 2006-09-16 03:35:57.176 AO: 62016 bytes free on soundcard 2006-09-16 03:35:57.184 AO: 59456 bytes free on soundcard 2006-09-16 03:35:57.192 AO: 60992 bytes free on soundcard 2006-09-16 03:35:57.200 AO: 62528 bytes free on soundcard 2006-09-16 03:35:57.208 AO: 59968 bytes free on soundcard 2006-09-16 03:35:57.216 AO: 61504 bytes free on soundcard 2006-09-16 03:35:57.225 AO: 59072 bytes free on soundcard 2006-09-16 03:35:57.234 AO: 60480 bytes free on soundcard 2006-09-16 03:35:57.242 AO: 62240 bytes free on soundcard 2006-09-16 03:35:57.249 AO: 59488 bytes free on soundcard 2006-09-16 03:35:57.260 AO: 61664 bytes free on soundcard 2006-09-16 03:35:57.268 AO: 59168 bytes free on soundcard 2006-09-16 03:35:57.276 AO: 60704 bytes free on soundcard 2006-09-16 03:35:57.281 OSD: InitCC708() -- begin 2006-09-16 03:35:57.285 AO: 62304 bytes free on soundcard 2006-09-16 03:35:57.295 AO: 60192 bytes free on soundcard 2006-09-16 03:35:57.300 AO: 61216 bytes free on soundcard 2006-09-16 03:35:57.311 AO: 63296 bytes free on soundcard 2006-09-16 03:35:57.316 AO: 60224 bytes free on soundcard 2006-09-16 03:35:57.324 AO: 61760 bytes free on soundcard 2006-09-16 03:35:57.333 AO: 59328 bytes free on soundcard 2006-09-16 03:35:57.340 AO: 60736 bytes free on soundcard 2006-09-16 03:35:57.349 AO: 62336 bytes free on soundcard 2006-09-16 03:35:57.358 AO: 60064 bytes free on soundcard 2006-09-16 03:35:57.366 AO: 61568 bytes free on soundcard 2006-09-16 03:35:57.372 AO: 58688 bytes free on soundcard 2006-09-16 03:35:57.380 AO: 60224 bytes free on soundcard 2006-09-16 03:35:57.388 AO: 61760 bytes free on soundcard 2006-09-16 03:35:57.397 AO: 59232 bytes free on soundcard 2006-09-16 03:35:57.406 AO: 61088 bytes free on soundcard 2006-09-16 03:35:57.412 AO: 62272 bytes free on soundcard 2006-09-16 03:35:57.424 AO: 60512 bytes free on soundcard 2006-09-16 03:35:57.433 AO: 62112 bytes free on soundcard 2006-09-16 03:35:57.441 AO: 59488 bytes free on soundcard 2006-09-16 03:35:57.449 AO: 61056 bytes free on soundcard 2006-09-16 03:35:57.457 AO: 62560 bytes free on soundcard 2006-09-16 03:35:57.465 AO: 60032 bytes free on soundcard 2006-09-16 03:35:57.476 AO: 62176 bytes free on soundcard 2006-09-16 03:35:57.484 AO: 59744 bytes free on soundcard 2006-09-16 03:35:57.492 AO: 61248 bytes free on soundcard 2006-09-16 03:35:57.500 AO: 62784 bytes free on soundcard 2006-09-16 03:35:57.510 AO: 60480 bytes free on soundcard 2006-09-16 03:35:57.516 AO: 61792 bytes free on soundcard 2006-09-16 03:35:57.529 AO: 60160 bytes free on soundcard 2006-09-16 03:35:57.537 AO: 61568 bytes free on soundcard 2006-09-16 03:35:57.545 AO: 59168 bytes free on soundcard 2006-09-16 03:35:57.552 AO: 60480 bytes free on soundcard 2006-09-16 03:35:57.561 AO: 62208 bytes free on soundcard 2006-09-16 03:35:57.568 AO: 59456 bytes free on soundcard 2006-09-16 03:35:57.578 AO: 61376 bytes free on soundcard 2006-09-16 03:35:57.585 AO: 62656 bytes free on soundcard 2006-09-16 03:35:57.593 AO: 60032 bytes free on soundcard 2006-09-16 03:35:57.600 AO: 61504 bytes free on soundcard 2006-09-16 03:35:57.609 AO: 59040 bytes free on soundcard 2006-09-16 03:35:57.616 AO: 60480 bytes free on soundcard 2006-09-16 03:35:57.625 AO: 62112 bytes free on soundcard 2006-09-16 03:35:57.632 AO: 59488 bytes free on soundcard 2006-09-16 03:35:57.641 AO: 61088 bytes free on soundcard 2006-09-16 03:35:57.648 AO: 62528 bytes free on soundcard 2006-09-16 03:35:57.657 AO: 60032 bytes free on soundcard 2006-09-16 03:35:57.667 AO: 62048 bytes free on soundcard 2006-09-16 03:35:57.673 AO: 59040 bytes free on soundcard 2006-09-16 03:35:57.684 AO: 61280 bytes free on soundcard 2006-09-16 03:35:57.692 AO: 62784 bytes free on soundcard 2006-09-16 03:35:57.701 AO: 60416 bytes free on soundcard 2006-09-16 03:35:57.708 AO: 61792 bytes free on soundcard 2006-09-16 03:35:57.716 AO: 59200 bytes free on soundcard 2006-09-16 03:35:57.725 AO: 60768 bytes free on soundcard 2006-09-16 03:35:57.732 AO: 62304 bytes free on soundcard 2006-09-16 03:35:57.740 AO: 59744 bytes free on soundcard 2006-09-16 03:35:57.748 AO: 61248 bytes free on soundcard 2006-09-16 03:35:57.756 AO: 62816 bytes free on soundcard 2006-09-16 03:35:57.767 AO: 60736 bytes free on soundcard 2006-09-16 03:35:57.772 AO: 61760 bytes free on soundcard 2006-09-16 03:35:57.783 AO: 59744 bytes free on soundcard 2006-09-16 03:35:57.788 AO: 60736 bytes free on soundcard 2006-09-16 03:35:57.796 AO: 62272 bytes free on soundcard 2006-09-16 03:35:57.804 AO: 59744 bytes free on soundcard 2006-09-16 03:35:57.812 AO: 61248 bytes free on soundcard 2006-09-16 03:35:57.820 AO: 62784 bytes free on soundcard 2006-09-16 03:35:57.829 AO: 60256 bytes free on soundcard 2006-09-16 03:35:57.837 AO: 61888 bytes free on soundcard 2006-09-16 03:35:57.844 AO: 59200 bytes free on soundcard 2006-09-16 03:35:57.855 AO: 61152 bytes free on soundcard 2006-09-16 03:35:57.860 AO: 62272 bytes free on soundcard 2006-09-16 03:35:57.871 AO: 60160 bytes free on soundcard 2006-09-16 03:35:57.876 AO: 61280 bytes free on soundcard 2006-09-16 03:35:57.884 AO: 62784 bytes free on soundcard 2006-09-16 03:35:57.892 AO: 60224 bytes free on soundcard 2006-09-16 03:35:57.900 AO: 61792 bytes free on soundcard 2006-09-16 03:35:57.908 AO: 59200 bytes free on soundcard 2006-09-16 03:35:57.916 AO: 60736 bytes free on soundcard 2006-09-16 03:35:57.924 AO: 62304 bytes free on soundcard 2006-09-16 03:35:57.932 AO: 59712 bytes free on soundcard 2006-09-16 03:35:57.940 AO: 61248 bytes free on soundcard 2006-09-16 03:35:57.948 AO: 62816 bytes free on soundcard 2006-09-16 03:35:57.956 AO: 60224 bytes free on soundcard 2006-09-16 03:35:57.964 AO: 61760 bytes free on soundcard 2006-09-16 03:35:57.973 AO: 59232 bytes free on soundcard 2006-09-16 03:35:57.980 AO: 60736 bytes free on soundcard 2006-09-16 03:35:57.988 AO: 62272 bytes free on soundcard 2006-09-16 03:35:57.997 AO: 59776 bytes free on soundcard 2006-09-16 03:35:58.004 AO: 61264 bytes free on soundcard 2006-09-16 03:35:58.012 AO: 62816 bytes free on soundcard 2006-09-16 03:35:58.020 AO: 60256 bytes free on soundcard 2006-09-16 03:35:58.033 AO: 62624 bytes free on soundcard 2006-09-16 03:35:58.044 AO: 60736 bytes free on soundcard 2006-09-16 03:35:58.052 AO: 62304 bytes free on soundcard 2006-09-16 03:35:58.064 AO: 60512 bytes free on soundcard 2006-09-16 03:35:58.072 AO: 62048 bytes free on soundcard 2006-09-16 03:35:58.081 AO: 59552 bytes free on soundcard 2006-09-16 03:35:58.083 OSD: InitCC708() -- end 2006-09-16 03:35:58.089 AO: 61120 bytes free on soundcard 2006-09-16 03:35:58.099 AO: 63008 bytes free on soundcard 2006-09-16 03:35:58.105 AO: 60032 bytes free on soundcard 2006-09-16 03:35:58.113 AO: 61600 bytes free on soundcard 2006-09-16 03:35:58.123 AO: 59392 bytes free on soundcard 2006-09-16 03:35:58.130 AO: 60736 bytes free on soundcard 2006-09-16 03:35:58.138 AO: 62304 bytes free on soundcard 2006-09-16 03:35:58.146 AO: 59776 bytes free on soundcard 2006-09-16 03:35:58.152 AO: 61024 bytes free on soundcard 2006-09-16 03:35:58.161 AO: 62656 bytes free on soundcard 2006-09-16 03:35:58.169 AO: 60032 bytes free on soundcard 2006-09-16 03:35:58.177 AO: 61600 bytes free on soundcard 2006-09-16 03:35:58.184 AO: 58976 bytes free on soundcard 2006-09-16 03:35:58.193 AO: 60608 bytes free on soundcard 2006-09-16 03:35:58.202 AO: 62336 bytes free on soundcard 2006-09-16 03:35:58.209 AO: 59552 bytes free on soundcard 2006-09-16 03:35:58.218 AO: 61344 bytes free on soundcard 2006-09-16 03:35:58.225 AO: 62656 bytes free on soundcard 2006-09-16 03:35:58.236 AO: 60672 bytes free on soundcard 2006-09-16 03:35:58.244 AO: 62304 bytes free on soundcard 2006-09-16 03:35:58.255 AO: 60288 bytes free on soundcard 2006-09-16 03:35:58.260 AO: 61280 bytes free on soundcard 2006-09-16 03:35:58.270 AO: 63072 bytes free on soundcard 2006-09-16 03:35:58.277 AO: 60320 bytes free on soundcard 2006-09-16 03:35:58.290 AO: 62208 bytes free on soundcard 2006-09-16 03:35:58.296 AO: 60000 bytes free on soundcard 2006-09-16 03:35:58.307 AO: 62112 bytes free on soundcard 2006-09-16 03:35:58.314 AO: 59328 bytes free on soundcard 2006-09-16 03:35:58.322 AO: 60896 bytes free on soundcard 2006-09-16 03:35:58.328 AO: 62048 bytes free on soundcard 2006-09-16 03:35:58.337 AO: 59584 bytes free on soundcard 2006-09-16 03:35:58.344 AO: 61024 bytes free on soundcard 2006-09-16 03:35:58.353 AO: 62624 bytes free on soundcard 2006-09-16 03:35:58.361 AO: 60032 bytes free on soundcard 2006-09-16 03:35:58.369 AO: 61664 bytes free on soundcard 2006-09-16 03:35:58.376 AO: 58976 bytes free on soundcard 2006-09-16 03:35:58.385 AO: 60640 bytes free on soundcard 2006-09-16 03:35:58.392 AO: 62048 bytes free on soundcard 2006-09-16 03:35:58.402 AO: 59808 bytes free on soundcard 2006-09-16 03:35:58.409 AO: 61056 bytes free on soundcard 2006-09-16 03:35:58.418 AO: 62912 bytes free on soundcard 2006-09-16 03:35:58.424 AO: 60000 bytes free on soundcard 2006-09-16 03:35:58.438 AO: 62528 bytes free on soundcard 2006-09-16 03:35:58.445 AO: 59808 bytes free on soundcard 2006-09-16 03:35:58.454 AO: 61664 bytes free on soundcard 2006-09-16 03:35:58.461 AO: 58752 bytes free on soundcard 2006-09-16 03:35:58.470 AO: 60640 bytes free on soundcard 2006-09-16 03:35:58.476 AO: 61792 bytes free on soundcard 2006-09-16 03:35:58.485 AO: 59328 bytes free on soundcard 2006-09-16 03:35:58.492 AO: 60768 bytes free on soundcard 2006-09-16 03:35:58.501 AO: 62368 bytes free on soundcard 2006-09-16 03:35:58.509 AO: 59808 bytes free on soundcard 2006-09-16 03:35:58.517 AO: 61344 bytes free on soundcard 2006-09-16 03:35:58.525 AO: 62848 bytes free on soundcard 2006-09-16 03:35:58.533 AO: 60352 bytes free on soundcard 2006-09-16 03:35:58.540 AO: 61792 bytes free on soundcard 2006-09-16 03:35:58.549 AO: 59296 bytes free on soundcard 2006-09-16 03:35:58.557 AO: 60800 bytes free on soundcard 2006-09-16 03:35:58.565 AO: 62400 bytes free on soundcard 2006-09-16 03:35:58.573 AO: 59776 bytes free on soundcard 2006-09-16 03:35:58.581 AO: 61376 bytes free on soundcard 2006-09-16 03:35:58.588 AO: 62848 bytes free on soundcard 2006-09-16 03:35:58.597 AO: 60288 bytes free on soundcard 2006-09-16 03:35:58.605 AO: 61824 bytes free on soundcard 2006-09-16 03:35:58.615 AO: 59744 bytes free on soundcard 2006-09-16 03:35:58.620 AO: 60800 bytes free on soundcard 2006-09-16 03:35:58.631 AO: 62816 bytes free on soundcard 2006-09-16 03:35:58.637 AO: 59840 bytes free on soundcard 2006-09-16 03:35:58.647 AO: 61792 bytes free on soundcard 2006-09-16 03:35:58.653 AO: 58816 bytes free on soundcard 2006-09-16 03:35:58.663 AO: 60800 bytes free on soundcard 2006-09-16 03:35:58.668 AO: 61792 bytes free on soundcard 2006-09-16 03:35:58.679 AO: 59680 bytes free on soundcard 2006-09-16 03:35:58.685 AO: 60832 bytes free on soundcard 2006-09-16 03:35:58.692 AO: 62336 bytes free on soundcard 2006-09-16 03:35:58.701 AO: 59840 bytes free on soundcard 2006-09-16 03:35:58.708 AO: 61312 bytes free on soundcard 2006-09-16 03:35:58.717 AO: 62880 bytes free on soundcard 2006-09-16 03:35:58.725 AO: 60352 bytes free on soundcard 2006-09-16 03:35:58.735 AO: 62400 bytes free on soundcard 2006-09-16 03:35:58.740 AO: 59264 bytes free on soundcard 2006-09-16 03:35:58.752 AO: 61408 bytes free on soundcard 2006-09-16 03:35:58.756 AO: 62336 bytes free on soundcard 2006-09-16 03:35:58.768 AO: 60416 bytes free on soundcard 2006-09-16 03:35:58.773 AO: 61376 bytes free on soundcard 2006-09-16 03:35:58.783 AO: 63392 bytes free on soundcard 2006-09-16 03:35:58.788 AO: 60288 bytes free on soundcard 2006-09-16 03:35:58.799 AO: 62400 bytes free on soundcard 2006-09-16 03:35:58.804 AO: 59264 bytes free on soundcard 2006-09-16 03:35:58.813 AO: 60864 bytes free on soundcard 2006-09-16 03:35:58.821 AO: 62400 bytes free on soundcard 2006-09-16 03:35:58.829 AO: 59840 bytes free on soundcard 2006-09-16 03:35:58.837 AO: 61408 bytes free on soundcard 2006-09-16 03:35:58.845 AO: 63040 bytes free on soundcard 2006-09-16 03:35:58.856 AO: 60960 bytes free on soundcard 2006-09-16 03:35:58.861 AO: 61888 bytes free on soundcard 2006-09-16 03:35:58.872 AO: 59904 bytes free on soundcard 2006-09-16 03:35:58.877 AO: 60864 bytes free on soundcard 2006-09-16 03:35:58.889 AO: 63232 bytes free on soundcard 2006-09-16 03:35:58.896 AO: 60544 bytes free on soundcard 2006-09-16 03:35:58.905 AO: 62240 bytes free on soundcard 2006-09-16 03:35:58.912 AO: 59520 bytes free on soundcard 2006-09-16 03:35:58.920 AO: 61056 bytes free on soundcard 2006-09-16 03:35:58.929 AO: 62624 bytes free on soundcard 2006-09-16 03:35:58.936 AO: 60032 bytes free on soundcard 2006-09-16 03:35:58.944 AO: 61568 bytes free on soundcard 2006-09-16 03:35:58.953 AO: 59040 bytes free on soundcard 2006-09-16 03:35:58.960 AO: 60544 bytes free on soundcard 2006-09-16 03:35:58.971 AO: 62592 bytes free on soundcard 2006-09-16 03:35:58.977 AO: 59552 bytes free on soundcard 2006-09-16 03:35:58.986 AO: 61440 bytes free on soundcard 2006-09-16 03:35:58.994 AO: 58848 bytes free on soundcard 2006-09-16 03:35:59.004 AO: 60736 bytes free on soundcard 2006-09-16 03:35:59.013 AO: 62368 bytes free on soundcard 2006-09-16 03:35:59.021 AO: 59872 bytes free on soundcard 2006-09-16 03:35:59.028 AO: 61312 bytes free on soundcard 2006-09-16 03:35:59.037 AO: 62976 bytes free on soundcard 2006-09-16 03:35:59.044 AO: 60288 bytes free on soundcard 2006-09-16 03:35:59.055 AO: 62336 bytes free on soundcard 2006-09-16 03:35:59.061 AO: 59296 bytes free on soundcard 2006-09-16 03:35:59.069 AO: 60864 bytes free on soundcard 2006-09-16 03:35:59.078 AO: 62560 bytes free on soundcard 2006-09-16 03:35:59.085 AO: 59840 bytes free on soundcard 2006-09-16 03:35:59.092 AO: 61312 bytes free on soundcard 2006-09-16 03:35:59.101 AO: 62912 bytes free on soundcard 2006-09-16 03:35:59.108 AO: 60288 bytes free on soundcard 2006-09-16 03:35:59.117 AO: 61888 bytes free on soundcard 2006-09-16 03:35:59.125 AO: 59424 bytes free on soundcard 2006-09-16 03:35:59.133 AO: 60864 bytes free on soundcard 2006-09-16 03:35:59.141 AO: 62496 bytes free on soundcard 2006-09-16 03:35:59.149 AO: 59872 bytes free on soundcard 2006-09-16 03:35:59.157 AO: 61344 bytes free on soundcard 2006-09-16 03:35:59.165 AO: 62912 bytes free on soundcard 2006-09-16 03:35:59.173 AO: 60320 bytes free on soundcard 2006-09-16 03:35:59.181 AO: 61920 bytes free on soundcard 2006-09-16 03:35:59.188 AO: 59264 bytes free on soundcard 2006-09-16 03:35:59.197 AO: 60896 bytes free on soundcard 2006-09-16 03:35:59.204 AO: 62336 bytes free on soundcard 2006-09-16 03:35:59.213 AO: 59840 bytes free on soundcard 2006-09-16 03:35:59.221 AO: 61344 bytes free on soundcard 2006-09-16 03:35:59.229 AO: 62912 bytes free on soundcard 2006-09-16 03:35:59.236 AO: 60288 bytes free on soundcard 2006-09-16 03:35:59.245 AO: 61920 bytes free on soundcard 2006-09-16 03:35:59.253 AO: 59296 bytes free on soundcard 2006-09-16 03:35:59.261 AO: 60864 bytes free on soundcard 2006-09-16 03:35:59.271 AO: 62752 bytes free on soundcard 2006-09-16 03:35:59.277 AO: 59872 bytes free on soundcard 2006-09-16 03:35:59.285 AO: 61344 bytes free on soundcard 2006-09-16 03:35:59.293 AO: 62944 bytes free on soundcard 2006-09-16 03:35:59.300 AO: 60320 bytes free on soundcard 2006-09-16 03:35:59.311 AO: 62400 bytes free on soundcard 2006-09-16 03:35:59.317 AO: 59296 bytes free on soundcard 2006-09-16 03:35:59.325 AO: 60864 bytes free on soundcard 2006-09-16 03:35:59.332 AO: 62368 bytes free on soundcard 2006-09-16 03:35:59.341 AO: 59872 bytes free on soundcard 2006-09-16 03:35:59.350 AO: 61600 bytes free on soundcard 2006-09-16 03:35:59.357 AO: 58816 bytes free on soundcard 2006-09-16 03:35:59.365 AO: 60320 bytes free on soundcard 2006-09-16 03:35:59.373 AO: 61920 bytes free on soundcard 2006-09-16 03:35:59.381 TV: StartPlayer(): took 3206 ms to start player. 2006-09-16 03:35:59.382 TV: Changing from None to WatchingLiveTV 2006-09-16 03:35:59.383 AO: 59840 bytes free on soundcard 2006-09-16 03:35:59.385 write -> 15 35 QUERY_RECORDER 1[]:[]FRONTEND_READY 2006-09-16 03:35:59.386 read <- 15 2 ok 2006-09-16 03:35:59.387 write -> 15 30 QUERY_RECORDER 1[]:[]GET_INPUT 2006-09-16 03:35:59.388 read <- 15 10 television 2006-09-16 03:35:59.389 AO: 60832 bytes free on soundcard 2006-09-16 03:35:59.398 AO: 62656 bytes free on soundcard 2006-09-16 03:35:59.376 NVP: ClearAfterSeek(1) 2006-09-16 03:35:59.399 VideoOutputXv: ClearAfterSeek() 2006-09-16 03:35:59.400 VideoOutputXv: DiscardFrames(0) 2006-09-16 03:35:59.400 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAAAA 2006-09-16 03:35:59.400 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done() 2006-09-16 03:35:59.401 VideoOutputXv: DiscardFrames(1) 2006-09-16 03:35:59.401 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAAAA 2006-09-16 03:35:59.401 VideoOutputXv: DiscardFrames() 2: AAAAAAAAAAAAAAAA 2006-09-16 03:35:59.402 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done() 2006-09-16 03:35:59.405 AO: OutputAudioLoop: audio paused 2006-09-16 03:35:59.405 AO: 59904 bytes free on soundcard 2006-09-16 03:35:59.407 The realtime priority setting is not enabled. 2006-09-16 03:35:59.414 AO: 61632 bytes free on soundcard 2006-09-16 03:35:59.421 AO: 58816 bytes free on soundcard 2006-09-16 03:35:59.428 AO: 60288 bytes free on soundcard 2006-09-16 03:35:59.437 AO: 61888 bytes free on soundcard 2006-09-16 03:35:59.445 AO: 59296 bytes free on soundcard 2006-09-16 03:35:59.453 AO: 60864 bytes free on soundcard 2006-09-16 03:35:59.460 AO: 62336 bytes free on soundcard 2006-09-16 03:35:59.470 AO: 60160 bytes free on soundcard 2006-09-16 03:35:59.477 AO: 61344 bytes free on soundcard 2006-09-16 03:35:59.485 AO: 63008 bytes free on soundcard 2006-09-16 03:35:59.492 AO: 60288 bytes free on soundcard 2006-09-16 03:35:59.503 AO: 62272 bytes free on soundcard 2006-09-16 03:35:59.509 AO: 59296 bytes free on soundcard 2006-09-16 03:35:59.681 AO: 65536 bytes free on soundcard 2006-09-16 03:35:59.907 AO: 63360 bytes free on soundcard 2006-09-16 03:35:59.912 AO: 60416 bytes free on soundcard 2006-09-16 03:35:59.921 AO: 62016 bytes free on soundcard 2006-09-16 03:35:59.929 AO: 59456 bytes free on soundcard 2006-09-16 03:35:59.932 nVidiaVideoSync: Could not open device /dev/nvidia0, No such device or address 2006-09-16 03:35:59.936 AO: 60928 bytes free on soundcard 2006-09-16 03:35:59.945 AO: 62496 bytes free on soundcard 2006-09-16 03:35:59.952 AO: 59904 bytes free on soundcard 2006-09-16 03:35:59.960 AO: 61440 bytes free on soundcard 2006-09-16 03:35:59.969 AO: 58912 bytes free on soundcard 2006-09-16 03:35:59.970 Using audio as timebase 2006-09-16 03:35:59.971 Video timing method: DRM 2006-09-16 03:35:59.971 Refresh rate: 20000, frame interval: 40000 2006-09-16 03:35:59.972 AFD: video timecode 90038 79238 880 612 2006-09-16 03:35:59.977 AO: 60448 bytes free on soundcard 2006-09-16 03:35:59.985 AO: 61984 bytes free on soundcard 2006-09-16 03:35:59.985 NVP: Waiting for prebuffer.. 0 uLAAAAAAAAAAAAAA 2006-09-16 03:35:59.986 AFD: audio timecode 81010 81010 900 924 2006-09-16 03:35:59.986 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=900 2006-09-16 03:35:59.990 AFD: audio timecode 83170 83170 924 948 2006-09-16 03:35:59.995 AO: 59872 bytes free on soundcard 2006-09-16 03:35:59.990 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=924 2006-09-16 03:36:00.000 AO: 60928 bytes free on soundcard 2006-09-16 03:36:00.000 AFD: audio timecode 85330 85330 948 972 2006-09-16 03:36:00.001 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=948 2006-09-16 03:36:00.003 AFD: audio timecode 87490 87490 972 996 2006-09-16 03:36:00.003 AO: _AddSamples bytes=4608, used=13825, free=754175, timecode=972 2006-09-16 03:36:00.013 AO: 63360 bytes free on soundcard 2006-09-16 03:36:00.020 AO: 60672 bytes free on soundcard 2006-09-16 03:36:00.029 AO: 62336 bytes free on soundcard 2006-09-16 03:36:00.030 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1 2006-09-16 03:36:00.036 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.037 AO: Broadcasting free space avail 2006-09-16 03:36:00.037 AO: Broadcasting free space avail 2006-09-16 03:36:00.038 AO: Broadcasting free space avail 2006-09-16 03:36:00.038 AO: Broadcasting free space avail 2006-09-16 03:36:00.038 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:00.038 AO: Broadcasting free space avail X Error: BadAlloc (insufficient resources for operation) 11 Major opcode: 141 Minor opcode: 18 Resource id: 0x2800016 2006-09-16 03:36:00.042 NVP: prebuffering pause 2006-09-16 03:36:00.042 NVP: Waiting for prebuffer.. 0 dLLAAAAAAAAAAAAA 2006-09-16 03:36:00.049 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.050 AO: 45792 bytes free on soundcard 2006-09-16 03:36:00.056 AO: 47104 bytes free on soundcard 2006-09-16 03:36:00.066 AO: 49024 bytes free on soundcard 2006-09-16 03:36:00.068 AFD: video timecode 82838 82838 920 996 2006-09-16 03:36:00.071 AFD: audio timecode 89650 89650 996 1020 2006-09-16 03:36:00.071 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=996 2006-09-16 03:36:00.072 AO: 50176 bytes free on soundcard 2006-09-16 03:36:00.082 AO: 52096 bytes free on soundcard 2006-09-16 03:36:00.089 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.089 AO: Broadcasting free space avail 2006-09-16 03:36:00.089 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:00.089 AO: Broadcasting free space avail 2006-09-16 03:36:00.088 NVP: A/V Divergence: 0.0375, Rate: 0.0375, Warpfactor: 1.0005, warpfactor_avg: 1 2006-09-16 03:36:00.102 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:00.103 AO: Broadcasting free space avail 2006-09-16 03:36:00.106 NVP: prebuffering pause 2006-09-16 03:36:00.107 NVP: Waiting for prebuffer.. 0 dLDLAAAAAAAAAAAA 2006-09-16 03:36:00.108 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.109 AO: 53056 bytes free on soundcard 2006-09-16 03:36:00.117 AO: 54624 bytes free on soundcard 2006-09-16 03:36:00.117 AFD: video timecode 86438 86438 960 1020 2006-09-16 03:36:00.119 AFD: audio timecode 91810 91810 1020 1044 2006-09-16 03:36:00.119 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1020 2006-09-16 03:36:00.121 AFD: audio timecode 93970 93970 1044 1068 2006-09-16 03:36:00.121 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=1044 2006-09-16 03:36:00.125 AO: 56096 bytes free on soundcard 2006-09-16 03:36:00.134 AO: 57984 bytes free on soundcard 2006-09-16 03:36:00.137 NVP: A/V Divergence: -0.084375, Rate: -0.121875, Warpfactor: 1, warpfactor_avg: 1 2006-09-16 03:36:00.139 NVP: prebuffering pause 2006-09-16 03:36:00.139 NVP: Waiting for prebuffer.. 0 DLADLAAAAAAAAAAA 2006-09-16 03:36:00.141 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.141 AO: 59264 bytes free on soundcard 2006-09-16 03:36:00.149 AO: 60768 bytes free on soundcard 2006-09-16 03:36:00.156 AO: 62208 bytes free on soundcard 2006-09-16 03:36:00.161 AFD: video timecode 100838 90038 1000 1068 2006-09-16 03:36:00.167 AO: 60224 bytes free on soundcard 2006-09-16 03:36:00.171 AFD: audio timecode 96130 96130 1068 1092 2006-09-16 03:36:00.171 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=1068 2006-09-16 03:36:00.173 AO: 61216 bytes free on soundcard 2006-09-16 03:36:00.173 AFD: audio timecode 98290 98290 1092 1116 2006-09-16 03:36:00.174 AO: _AddSamples bytes=4608, used=16385, free=751615, timecode=1092 2006-09-16 03:36:00.186 AO: 63744 bytes free on soundcard 2006-09-16 03:36:00.190 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1 2006-09-16 03:36:00.193 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.193 AO: Broadcasting free space avail 2006-09-16 03:36:00.193 AO: Broadcasting free space avail 2006-09-16 03:36:00.193 AO: Broadcasting free space avail 2006-09-16 03:36:00.194 AO: Broadcasting free space avail 2006-09-16 03:36:00.194 AO: Broadcasting free space avail 2006-09-16 03:36:00.194 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.194 AO: Broadcasting free space avail 2006-09-16 03:36:00.203 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.203 AO: Broadcasting free space avail 2006-09-16 03:36:00.205 NVP: prebuffering pause 2006-09-16 03:36:00.206 NVP: Waiting for prebuffer.. 0 AdAALLAAAAAAAAAA 2006-09-16 03:36:00.209 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.209 AO: 43616 bytes free on soundcard 2006-09-16 03:36:00.213 AFD: video timecode 93638 93638 1040 1116 2006-09-16 03:36:00.218 AO: 45280 bytes free on soundcard 2006-09-16 03:36:00.218 AFD: audio timecode 100450 100450 1116 1140 2006-09-16 03:36:00.219 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1116 2006-09-16 03:36:00.225 AO: 46656 bytes free on soundcard 2006-09-16 03:36:00.229 NVP: A/V Divergence: 0.025, Rate: 0.025, Warpfactor: 1.0004, warpfactor_avg: 1 2006-09-16 03:36:00.233 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.233 AO: Broadcasting free space avail 2006-09-16 03:36:00.234 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:00.234 AO: Broadcasting free space avail 2006-09-16 03:36:00.240 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:00.241 AO: Broadcasting free space avail 2006-09-16 03:36:00.245 NVP: prebuffering pause 2006-09-16 03:36:00.245 NVP: Waiting for prebuffer.. 0 AdAALDLAAAAAAAAA 2006-09-16 03:36:00.246 AFD: video timecode 97238 97238 1080 1140 2006-09-16 03:36:00.249 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.249 AO: 47264 bytes free on soundcard 2006-09-16 03:36:00.248 AFD: audio timecode 102610 102610 1140 1164 2006-09-16 03:36:00.251 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1140 2006-09-16 03:36:00.253 AFD: audio timecode 104770 104770 1164 1188 2006-09-16 03:36:00.253 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=1164 2006-09-16 03:36:00.257 AO: 48704 bytes free on soundcard 2006-09-16 03:36:00.265 AO: 50336 bytes free on soundcard 2006-09-16 03:36:00.269 NVP: A/V Divergence: 0.05, Rate: 0.025, Warpfactor: 1.0006, warpfactor_avg: 1 2006-09-16 03:36:00.272 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.273 AO: Broadcasting free space avail 2006-09-16 03:36:00.273 AO: Broadcasting free space avail 2006-09-16 03:36:00.273 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:00.274 AO: Broadcasting free space avail 2006-09-16 03:36:00.285 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:00.285 AO: Broadcasting free space avail 2006-09-16 03:36:00.286 NVP: prebuffering pause 2006-09-16 03:36:00.287 NVP: Waiting for prebuffer.. 0 ADAALADLAAAAAAAA 2006-09-16 03:36:00.287 AFD: video timecode 111638 100838 1120 1188 2006-09-16 03:36:00.289 AFD: audio timecode 106930 106930 1188 1212 2006-09-16 03:36:00.289 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1188 2006-09-16 03:36:00.292 AFD: audio timecode 109090 109090 1212 1236 2006-09-16 03:36:00.293 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.293 AO: 47488 bytes free on soundcard 2006-09-16 03:36:00.292 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=1212 2006-09-16 03:36:00.301 AO: 49024 bytes free on soundcard 2006-09-16 03:36:00.308 NVP: A/V Divergence: 0.05625, Rate: 0.00625, Warpfactor: 1.0005, warpfactor_avg: 1 2006-09-16 03:36:00.310 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.311 AO: Broadcasting free space avail 2006-09-16 03:36:00.311 AO: Broadcasting free space avail 2006-09-16 03:36:00.311 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.312 AO: Broadcasting free space avail 2006-09-16 03:36:00.318 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.319 AO: Broadcasting free space avail 2006-09-16 03:36:00.325 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.325 AO: Broadcasting free space avail 2006-09-16 03:36:00.327 NVP: prebuffering pause 2006-09-16 03:36:00.327 NVP: Waiting for prebuffer.. 0 AAAAdAALLAAAAAAA 2006-09-16 03:36:00.334 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.334 AO: 47200 bytes free on soundcard 2006-09-16 03:36:00.335 AFD: video timecode 104438 104438 1160 1236 2006-09-16 03:36:00.337 AFD: audio timecode 111250 111250 1236 1260 2006-09-16 03:36:00.338 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1236 2006-09-16 03:36:00.340 AO: 48416 bytes free on soundcard 2006-09-16 03:36:00.348 NVP: A/V Divergence: 0.067175, Rate: 0.010925, Warpfactor: 1.00062, warpfactor_avg: 1 2006-09-16 03:36:00.352 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.353 AO: Broadcasting free space avail 2006-09-16 03:36:00.353 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:00.353 AO: Broadcasting free space avail 2006-09-16 03:36:00.361 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:00.361 AO: Broadcasting free space avail 2006-09-16 03:36:00.370 NVP: prebuffering pause 2006-09-16 03:36:00.371 NVP: Waiting for prebuffer.. 0 AAAAdAALDLAAAAAA 2006-09-16 03:36:00.369 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:00.371 AO: Broadcasting free space avail 2006-09-16 03:36:00.377 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.377 AO: 51360 bytes free on soundcard 2006-09-16 03:36:00.387 AO: 53216 bytes free on soundcard 2006-09-16 03:36:00.389 AFD: video timecode 108038 108038 1200 1260 2006-09-16 03:36:00.390 AFD: audio timecode 113410 113410 1260 1284 2006-09-16 03:36:00.391 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=1260 2006-09-16 03:36:00.393 AO: 54368 bytes free on soundcard 2006-09-16 03:36:00.396 AFD: audio timecode 115570 115570 1284 1308 2006-09-16 03:36:00.398 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=1284 2006-09-16 03:36:00.398 AFD: HandleGopStart: gopset not set, syncing positionMap 2006-09-16 03:36:00.398 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1) 2006-09-16 03:36:00.401 AO: 55840 bytes free on soundcard 2006-09-16 03:36:00.410 NVP: A/V Divergence: 0.044125, Rate: -0.02305, Warpfactor: 1.00017, warpfactor_avg: 1 2006-09-16 03:36:00.411 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.411 AO: Broadcasting free space avail 2006-09-16 03:36:00.412 AO: Broadcasting free space avail 2006-09-16 03:36:00.412 AO: Broadcasting free space avail 2006-09-16 03:36:00.412 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.413 AO: Broadcasting free space avail 2006-09-16 03:36:00.413 Position map filled from DB to: 6 2006-09-16 03:36:00.414 SyncPositionMap watchingrecording, from DB: 6 entries 2006-09-16 03:36:00.414 write -> 15 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN 2006-09-16 03:36:00.415 read <- 15 9 0[]:[]108 2006-09-16 03:36:00.416 Filling position map from 7 to 9 2006-09-16 03:36:00.416 write -> 15 50 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]7[]:[]9 2006-09-16 03:36:00.417 read <- 15 85 0[]:[]7[]:[]0[]:[]2324480[]:[]0[]:[]8[]:[]0[]:[]2713600[]:[]0[]:[... 2006-09-16 03:36:00.418 Position map filled from Encoder to: 9 2006-09-16 03:36:00.418 SyncPositionMap watchingrecording total: 9 entries 2006-09-16 03:36:00.423 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.423 AO: Broadcasting free space avail 2006-09-16 03:36:00.428 NVP: prebuffering pause 2006-09-16 03:36:00.428 NVP: Waiting for prebuffer.. 0 AAAAdAALAdAAAAAA 2006-09-16 03:36:00.429 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.429 AO: 49120 bytes free on soundcard 2006-09-16 03:36:00.418 SyncPositionMap, new totframes: 108, new length: 4, posMap size: 9 2006-09-16 03:36:00.430 AFD: HandleGopStart: Initial key frame distance: 12. 2006-09-16 03:36:00.438 AO: 50784 bytes free on soundcard 2006-09-16 03:36:00.445 AO: 52064 bytes free on soundcard 2006-09-16 03:36:00.456 AO: 54272 bytes free on soundcard 2006-09-16 03:36:00.465 AO: 55840 bytes free on soundcard 2006-09-16 03:36:00.466 AFD: video timecode 122438 111638 1240 1308 2006-09-16 03:36:00.468 AFD: audio timecode 117730 117730 1308 1332 2006-09-16 03:36:00.468 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1308 2006-09-16 03:36:00.470 AFD: audio timecode 119890 119890 1332 1356 2006-09-16 03:36:00.470 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=1332 2006-09-16 03:36:00.473 AO: 57408 bytes free on soundcard 2006-09-16 03:36:00.481 AO: 58944 bytes free on soundcard 2006-09-16 03:36:00.488 NVP: A/V Divergence: -0.0794, Rate: -0.123525, Warpfactor: 0.999668, warpfactor_avg: 1 2006-09-16 03:36:00.489 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.489 AO: Broadcasting free space avail 2006-09-16 03:36:00.490 AO: Broadcasting free space avail 2006-09-16 03:36:00.490 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.490 AO: Broadcasting free space avail 2006-09-16 03:36:00.496 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.497 AO: Broadcasting free space avail 2006-09-16 03:36:00.501 AFD: video timecode 115238 115238 1280 1356 2006-09-16 03:36:00.505 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.505 AO: Broadcasting free space avail 2006-09-16 03:36:00.516 NVP: A/V Divergence: -0.4533, Rate: -0.3739, Warpfactor: 0.999169, warpfactor_avg: 1 2006-09-16 03:36:00.517 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.517 AO: Broadcasting free space avail 2006-09-16 03:36:00.504 AFD: audio timecode 122050 122050 1356 1380 2006-09-16 03:36:00.518 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1356 2006-09-16 03:36:00.530 AO: Broadcasting free space avail 2006-09-16 03:36:00.530 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:00.531 AO: Broadcasting free space avail 2006-09-16 03:36:00.532 NVP: prebuffering pause 2006-09-16 03:36:00.532 NVP: Waiting for prebuffer.. 0 AAAAAAAdAALDLAAA 2006-09-16 03:36:00.537 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.537 AO: 57472 bytes free on soundcard 2006-09-16 03:36:00.548 AO: 59488 bytes free on soundcard 2006-09-16 03:36:00.553 AO: 60608 bytes free on soundcard 2006-09-16 03:36:00.561 AO: 62112 bytes free on soundcard 2006-09-16 03:36:00.569 AO: 59456 bytes free on soundcard 2006-09-16 03:36:00.577 AO: 61088 bytes free on soundcard 2006-09-16 03:36:00.585 AO: 62528 bytes free on soundcard 2006-09-16 03:36:00.586 AFD: video timecode 118838 118838 1320 1380 2006-09-16 03:36:00.588 AFD: audio timecode 124210 124210 1380 1404 2006-09-16 03:36:00.588 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1380 2006-09-16 03:36:00.590 AFD: audio timecode 126370 126370 1404 1428 2006-09-16 03:36:00.590 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=1404 2006-09-16 03:36:00.595 AO: 60384 bytes free on soundcard 2006-09-16 03:36:00.601 AO: 61504 bytes free on soundcard 2006-09-16 03:36:00.608 NVP: A/V Divergence: -0.602475, Rate: -0.149175, Warpfactor: 0.998669, warpfactor_avg: 1 2006-09-16 03:36:00.612 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.612 AO: Broadcasting free space avail 2006-09-16 03:36:00.613 AO: Broadcasting free space avail 2006-09-16 03:36:00.613 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.613 AO: Broadcasting free space avail 2006-09-16 03:36:00.621 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.621 AO: Broadcasting free space avail 2006-09-16 03:36:00.630 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.630 AO: Broadcasting free space avail 2006-09-16 03:36:00.632 NVP: prebuffering pause 2006-09-16 03:36:00.632 NVP: Waiting for prebuffer.. 0 AAAAAAAAAALADLAA 2006-09-16 03:36:00.633 AFD: video timecode 133238 122438 1360 1428 2006-09-16 03:36:00.636 AFD: audio timecode 128530 128530 1428 1452 2006-09-16 03:36:00.636 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.637 AO: 56160 bytes free on soundcard 2006-09-16 03:36:00.636 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1428 2006-09-16 03:36:00.638 AFD: audio timecode 130690 130690 1452 1476 2006-09-16 03:36:00.639 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1452 2006-09-16 03:36:00.649 AO: 58464 bytes free on soundcard 2006-09-16 03:36:00.653 NVP: A/V Divergence: -0.6831, Rate: -0.080625, Warpfactor: 0.99817, warpfactor_avg: 1 2006-09-16 03:36:00.657 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.658 AO: Broadcasting free space avail 2006-09-16 03:36:00.658 AO: Broadcasting free space avail 2006-09-16 03:36:00.658 AO: Broadcasting free space avail 2006-09-16 03:36:00.658 AO: Broadcasting free space avail 2006-09-16 03:36:00.667 AO: Broadcasting free space avail 2006-09-16 03:36:00.668 NVP: prebuffering pause 2006-09-16 03:36:00.669 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAdAALLA 2006-09-16 03:36:00.673 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.673 AO: 50880 bytes free on soundcard 2006-09-16 03:36:00.683 AO: 52704 bytes free on soundcard 2006-09-16 03:36:00.685 AFD: video timecode 126038 126038 1400 1476 2006-09-16 03:36:00.686 AFD: audio timecode 132850 132850 1476 1500 2006-09-16 03:36:00.687 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1476 2006-09-16 03:36:00.689 AO: 53920 bytes free on soundcard 2006-09-16 03:36:00.701 AO: 56192 bytes free on soundcard 2006-09-16 03:36:00.708 NVP: A/V Divergence: -0.731075, Rate: -0.047975, Warpfactor: 0.997671, warpfactor_avg: 1 2006-09-16 03:36:00.709 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.709 AO: Broadcasting free space avail 2006-09-16 03:36:00.710 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.710 AO: Broadcasting free space avail 2006-09-16 03:36:00.718 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.719 AO: Broadcasting free space avail 2006-09-16 03:36:00.725 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:00.725 AO: Broadcasting free space avail 2006-09-16 03:36:00.729 NVP: prebuffering pause 2006-09-16 03:36:00.730 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAdAALDL 2006-09-16 03:36:00.730 AFD: video timecode 129638 129638 1440 1500 2006-09-16 03:36:00.736 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.740 AO: 59616 bytes free on soundcard 2006-09-16 03:36:00.744 AFD: audio timecode 135009 135009 1500 1524 2006-09-16 03:36:00.745 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1500 2006-09-16 03:36:00.747 AFD: audio timecode 137169 137169 1524 1548 2006-09-16 03:36:00.747 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=1524 2006-09-16 03:36:00.752 AO: 61920 bytes free on soundcard 2006-09-16 03:36:00.761 AO: 59424 bytes free on soundcard 2006-09-16 03:36:00.768 NVP: A/V Divergence: -0.89205, Rate: -0.160975, Warpfactor: 0.997172, warpfactor_avg: 0.999999 2006-09-16 03:36:00.769 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.769 AO: Broadcasting free space avail 2006-09-16 03:36:00.769 AO: Broadcasting free space avail 2006-09-16 03:36:00.769 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.770 AO: Broadcasting free space avail 2006-09-16 03:36:00.777 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.777 AO: Broadcasting free space avail 2006-09-16 03:36:00.785 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.785 AO: Broadcasting free space avail 2006-09-16 03:36:00.787 NVP: prebuffering pause 2006-09-16 03:36:00.787 NVP: Waiting for prebuffer.. 0 AALAAAAAAADAALAD 2006-09-16 03:36:00.788 AFD: video timecode 144038 133238 1480 1548 2006-09-16 03:36:00.790 AFD: audio timecode 139329 139329 1548 1572 2006-09-16 03:36:00.790 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1548 2006-09-16 03:36:00.793 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.793 AO: 57472 bytes free on soundcard 2006-09-16 03:36:00.796 AFD: audio timecode 141489 141489 1572 1596 2006-09-16 03:36:00.797 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=1572 2006-09-16 03:36:00.801 AO: 58944 bytes free on soundcard 2006-09-16 03:36:00.810 AO: 60800 bytes free on soundcard 2006-09-16 03:36:00.815 NVP: A/V Divergence: -0.994025, Rate: -0.101975, Warpfactor: 0.996673, warpfactor_avg: 0.999999 2006-09-16 03:36:00.817 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.817 AO: Broadcasting free space avail 2006-09-16 03:36:00.817 AO: Broadcasting free space avail 2006-09-16 03:36:00.817 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:00.818 AO: Broadcasting free space avail 2006-09-16 03:36:00.826 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:00.826 AO: Broadcasting free space avail 2006-09-16 03:36:00.827 NVP: prebuffering pause 2006-09-16 03:36:00.827 NVP: Waiting for prebuffer.. 0 AALLAAAAAAAAAdAA 2006-09-16 03:36:00.832 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.833 AO: 56928 bytes free on soundcard 2006-09-16 03:36:00.837 AFD: video timecode 136838 136838 1520 1596 2006-09-16 03:36:00.841 AO: 58496 bytes free on soundcard 2006-09-16 03:36:00.842 AFD: audio timecode 143649 143649 1596 1620 2006-09-16 03:36:00.843 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1596 2006-09-16 03:36:00.849 AO: 60000 bytes free on soundcard 2006-09-16 03:36:00.850 NVP: A/V Divergence: -1.0705, Rate: -0.076475, Warpfactor: 0.996175, warpfactor_avg: 0.999999 2006-09-16 03:36:00.857 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.857 AO: Broadcasting free space avail 2006-09-16 03:36:00.858 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.858 AO: Broadcasting free space avail 2006-09-16 03:36:00.865 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:00.865 AO: Broadcasting free space avail 2006-09-16 03:36:00.866 NVP: prebuffering pause 2006-09-16 03:36:00.867 NVP: Waiting for prebuffer.. 0 LALDAAAAAAAAAdAA 2006-09-16 03:36:00.878 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.878 AO: 61536 bytes free on soundcard 2006-09-16 03:36:00.880 AFD: video timecode 140438 140438 1560 1620 2006-09-16 03:36:00.882 AFD: audio timecode 145809 145809 1620 1644 2006-09-16 03:36:00.882 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1620 2006-09-16 03:36:00.885 AO: 58688 bytes free on soundcard 2006-09-16 03:36:00.887 AFD: audio timecode 147969 147969 1644 1668 2006-09-16 03:36:00.887 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1644 2006-09-16 03:36:00.896 AO: 60928 bytes free on soundcard 2006-09-16 03:36:00.905 AO: 62560 bytes free on soundcard 2006-09-16 03:36:00.913 AO: 60032 bytes free on soundcard 2006-09-16 03:36:00.916 NVP: A/V Divergence: -1.12162, Rate: -0.051125, Warpfactor: 0.995677, warpfactor_avg: 0.999999 2006-09-16 03:36:00.918 NVP: prebuffering pause 2006-09-16 03:36:00.918 NVP: Waiting for prebuffer.. 0 DALAALAAAAAAADAA 2006-09-16 03:36:00.921 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:00.921 AO: 61568 bytes free on soundcard 2006-09-16 03:36:00.931 AO: 59424 bytes free on soundcard 2006-09-16 03:36:00.937 AO: 60480 bytes free on soundcard 2006-09-16 03:36:00.941 AFD: video timecode 154837 144038 1600 1668 2006-09-16 03:36:00.945 AO: 62048 bytes free on soundcard 2006-09-16 03:36:00.947 AFD: audio timecode 150129 150129 1668 1692 2006-09-16 03:36:00.947 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=1668 2006-09-16 03:36:00.948 AFD: audio timecode 152289 152289 1692 1716 2006-09-16 03:36:00.949 AO: _AddSamples bytes=4608, used=16897, free=751103, timecode=1692 2006-09-16 03:36:00.953 AO: 59552 bytes free on soundcard 2006-09-16 03:36:00.961 AO: 61088 bytes free on soundcard 2006-09-16 03:36:00.968 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.996174, warpfactor_avg: 0.999998 2006-09-16 03:36:00.969 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:00.969 AO: Broadcasting free space avail 2006-09-16 03:36:00.970 AO: Broadcasting free space avail 2006-09-16 03:36:00.970 AO: Broadcasting free space avail 2006-09-16 03:36:00.970 AO: Broadcasting free space avail 2006-09-16 03:36:00.971 AO: Broadcasting free space avail 2006-09-16 03:36:00.971 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:00.971 AO: Broadcasting free space avail 2006-09-16 03:36:00.978 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:00.978 AO: Broadcasting free space avail 2006-09-16 03:36:00.981 AFD: video timecode 147638 147638 1640 1716 2006-09-16 03:36:00.983 AFD: audio timecode 154449 154449 1716 1740 2006-09-16 03:36:00.984 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1716 2006-09-16 03:36:00.985 AO: Broadcasting free space avail 2006-09-16 03:36:00.985 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.986 AO: Broadcasting free space avail 2006-09-16 03:36:00.993 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:00.993 AO: Broadcasting free space avail 2006-09-16 03:36:00.995 NVP: A/V Divergence: -0.04375, Rate: -0.04375, Warpfactor: 0.996673, warpfactor_avg: 0.999998 2006-09-16 03:36:01.001 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.001 AO: Broadcasting free space avail 2006-09-16 03:36:01.009 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.010 AO: Broadcasting free space avail 2006-09-16 03:36:01.017 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.017 AO: Broadcasting free space avail 2006-09-16 03:36:01.021 AFD: video timecode 151237 151237 1680 1740 2006-09-16 03:36:01.023 AFD: audio timecode 156609 156609 1740 1764 2006-09-16 03:36:01.023 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1740 2006-09-16 03:36:01.028 AO: Broadcasting free space avail 2006-09-16 03:36:01.028 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.028 AO: Broadcasting free space avail 2006-09-16 03:36:01.031 AFD: audio timecode 158769 158769 1764 1788 2006-09-16 03:36:01.031 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1764 2006-09-16 03:36:01.033 AO: Broadcasting free space avail 2006-09-16 03:36:01.033 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.033 AO: Broadcasting free space avail 2006-09-16 03:36:01.043 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.044 AO: Broadcasting free space avail 2006-09-16 03:36:01.049 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.049 AO: Broadcasting free space avail 2006-09-16 03:36:01.060 NVP: A/V Divergence: -0.23905, Rate: -0.1953, Warpfactor: 0.996523, warpfactor_avg: 0.999998 2006-09-16 03:36:01.061 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.062 AO: Broadcasting free space avail 2006-09-16 03:36:01.069 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.069 AO: Broadcasting free space avail 2006-09-16 03:36:01.080 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.081 AO: Broadcasting free space avail 2006-09-16 03:36:01.082 AFD: video timecode 165637 154837 1720 1788 2006-09-16 03:36:01.086 AFD: audio timecode 160929 160929 1788 1812 2006-09-16 03:36:01.087 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1788 2006-09-16 03:36:01.088 AFD: audio timecode 163089 163089 1812 1836 2006-09-16 03:36:01.089 AO: Broadcasting free space avail 2006-09-16 03:36:01.089 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:01.089 AO: Broadcasting free space avail 2006-09-16 03:36:01.098 NVP: A/V Divergence: -0.366775, Rate: -0.127725, Warpfactor: 0.996042, warpfactor_avg: 0.999998 2006-09-16 03:36:01.099 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:01.099 AO: Broadcasting free space avail 2006-09-16 03:36:01.100 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1812 2006-09-16 03:36:01.105 AO: Broadcasting free space avail 2006-09-16 03:36:01.105 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.105 AO: Broadcasting free space avail 2006-09-16 03:36:01.114 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.114 AO: Broadcasting free space avail 2006-09-16 03:36:01.121 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.121 AO: Broadcasting free space avail 2006-09-16 03:36:01.132 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.132 AO: Broadcasting free space avail 2006-09-16 03:36:01.133 NVP: prebuffering pause 2006-09-16 03:36:01.134 NVP: Waiting for prebuffer.. 0 AAAAAdAALLAAAAAA 2006-09-16 03:36:01.134 AFD: video timecode 158437 158437 1760 1836 2006-09-16 03:36:01.136 AFD: audio timecode 165249 165249 1836 1860 2006-09-16 03:36:01.136 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=1836 2006-09-16 03:36:01.141 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.141 AO: 54656 bytes free on soundcard 2006-09-16 03:36:01.152 NVP: A/V Divergence: -0.481325, Rate: -0.11455, Warpfactor: 0.995544, warpfactor_avg: 0.999997 2006-09-16 03:36:01.152 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.153 AO: Broadcasting free space avail 2006-09-16 03:36:01.153 AO: Broadcasting free space avail 2006-09-16 03:36:01.153 AO: Broadcasting free space avail 2006-09-16 03:36:01.165 AO: Broadcasting free space avail 2006-09-16 03:36:01.167 NVP: prebuffering pause 2006-09-16 03:36:01.167 NVP: Waiting for prebuffer.. 0 AAAALdAALDAAAAAA 2006-09-16 03:36:01.173 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.173 AO: 52640 bytes free on soundcard 2006-09-16 03:36:01.183 AO: 54560 bytes free on soundcard 2006-09-16 03:36:01.184 AFD: video timecode 162037 162037 1800 1860 2006-09-16 03:36:01.187 AFD: audio timecode 167409 167409 1860 1884 2006-09-16 03:36:01.188 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1860 2006-09-16 03:36:01.189 AO: 55680 bytes free on soundcard 2006-09-16 03:36:01.191 AFD: audio timecode 169569 169569 1884 1908 2006-09-16 03:36:01.192 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=1884 2006-09-16 03:36:01.197 AO: 57216 bytes free on soundcard 2006-09-16 03:36:01.205 AO: 58752 bytes free on soundcard 2006-09-16 03:36:01.213 AO: 60256 bytes free on soundcard 2006-09-16 03:36:01.220 NVP: A/V Divergence: -0.523475, Rate: -0.04215, Warpfactor: 0.995472, warpfactor_avg: 0.999997 2006-09-16 03:36:01.221 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.221 AO: Broadcasting free space avail 2006-09-16 03:36:01.222 AO: Broadcasting free space avail 2006-09-16 03:36:01.222 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:01.222 AO: Broadcasting free space avail 2006-09-16 03:36:01.223 AFD: video timecode 176437 165637 1840 1908 2006-09-16 03:36:01.224 AFD: audio timecode 171729 171729 1908 1932 2006-09-16 03:36:01.225 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1908 2006-09-16 03:36:01.228 AFD: audio timecode 173889 173889 1932 1956 2006-09-16 03:36:01.234 NVP: A/V Divergence: -0.7051, Rate: -0.181625, Warpfactor: 0.994975, warpfactor_avg: 0.999997 2006-09-16 03:36:01.228 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=1932 2006-09-16 03:36:01.235 AO: Broadcasting free space avail 2006-09-16 03:36:01.235 AO: Broadcasting free space avail 2006-09-16 03:36:01.236 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.236 AO: Broadcasting free space avail 2006-09-16 03:36:01.241 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.242 AO: Broadcasting free space avail 2006-09-16 03:36:01.249 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.250 AO: Broadcasting free space avail 2006-09-16 03:36:01.251 NVP: prebuffering pause 2006-09-16 03:36:01.252 NVP: Waiting for prebuffer.. 0 AAAAAAALdAALAAAA 2006-09-16 03:36:01.257 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.257 AO: 52384 bytes free on soundcard 2006-09-16 03:36:01.266 AO: 54080 bytes free on soundcard 2006-09-16 03:36:01.273 AO: 55392 bytes free on soundcard 2006-09-16 03:36:01.274 AFD: video timecode 169237 169237 1880 1956 2006-09-16 03:36:01.276 AFD: audio timecode 176049 176049 1956 1980 2006-09-16 03:36:01.276 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1956 2006-09-16 03:36:01.282 AO: 57152 bytes free on soundcard 2006-09-16 03:36:01.289 AO: 58464 bytes free on soundcard 2006-09-16 03:36:01.293 NVP: A/V Divergence: -0.722575, Rate: -0.017475, Warpfactor: 0.994477, warpfactor_avg: 0.999996 2006-09-16 03:36:01.299 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.299 AO: Broadcasting free space avail 2006-09-16 03:36:01.300 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.300 AO: Broadcasting free space avail 2006-09-16 03:36:01.305 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.305 AO: Broadcasting free space avail 2006-09-16 03:36:01.317 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.317 AO: Broadcasting free space avail 2006-09-16 03:36:01.319 NVP: prebuffering pause 2006-09-16 03:36:01.319 NVP: Waiting for prebuffer.. 0 AAAAAAADdAALLAAA 2006-09-16 03:36:01.320 AFD: video timecode 172837 172837 1920 1980 2006-09-16 03:36:01.322 AFD: audio timecode 178209 178209 1980 2004 2006-09-16 03:36:01.322 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1980 2006-09-16 03:36:01.325 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.325 AO: 61344 bytes free on soundcard 2006-09-16 03:36:01.324 AFD: audio timecode 180369 180369 2004 2028 2006-09-16 03:36:01.327 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=2004 2006-09-16 03:36:01.335 AO: 63296 bytes free on soundcard 2006-09-16 03:36:01.341 AO: 60288 bytes free on soundcard 2006-09-16 03:36:01.351 AO: 62240 bytes free on soundcard 2006-09-16 03:36:01.357 AO: 59232 bytes free on soundcard 2006-09-16 03:36:01.361 NVP: A/V Divergence: -0.910675, Rate: -0.1881, Warpfactor: 0.99398, warpfactor_avg: 0.999996 2006-09-16 03:36:01.362 AFD: video timecode 187237 176437 1960 2028 2006-09-16 03:36:01.363 AFD: audio timecode 182529 182529 2028 2052 2006-09-16 03:36:01.364 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=2028 2006-09-16 03:36:01.368 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.368 AO: Broadcasting free space avail 2006-09-16 03:36:01.369 AO: Broadcasting free space avail 2006-09-16 03:36:01.369 AO: Broadcasting free space avail 2006-09-16 03:36:01.369 AO: Broadcasting free space avail 2006-09-16 03:36:01.369 AO: Broadcasting free space avail 2006-09-16 03:36:01.373 AFD: audio timecode 184689 184689 2052 2076 2006-09-16 03:36:01.374 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2052 2006-09-16 03:36:01.380 NVP: A/V Divergence: -0.88925, Rate: 0.021425, Warpfactor: 0.993483, warpfactor_avg: 0.999996 2006-09-16 03:36:01.380 AO: Broadcasting free space avail 2006-09-16 03:36:01.381 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:01.381 AO: Broadcasting free space avail 2006-09-16 03:36:01.389 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:01.390 AO: Broadcasting free space avail 2006-09-16 03:36:01.392 NVP: prebuffering pause 2006-09-16 03:36:01.392 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAdAALL 2006-09-16 03:36:01.397 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.400 AO: 46912 bytes free on soundcard 2006-09-16 03:36:01.405 AO: 47936 bytes free on soundcard 2006-09-16 03:36:01.413 AO: 49568 bytes free on soundcard 2006-09-16 03:36:01.421 AO: 51040 bytes free on soundcard 2006-09-16 03:36:01.425 AFD: video timecode 180037 180037 2000 2076 2006-09-16 03:36:01.429 AO: 52704 bytes free on soundcard 2006-09-16 03:36:01.432 AFD: audio timecode 186849 186849 2076 2100 2006-09-16 03:36:01.437 AO: 54144 bytes free on soundcard 2006-09-16 03:36:01.436 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2076 2006-09-16 03:36:01.445 AO: 55712 bytes free on soundcard 2006-09-16 03:36:01.453 AO: 57152 bytes free on soundcard 2006-09-16 03:36:01.457 NVP: A/V Divergence: -0.723175, Rate: 0.166075, Warpfactor: 0.99398, warpfactor_avg: 0.999995 2006-09-16 03:36:01.459 NVP: prebuffering pause 2006-09-16 03:36:01.459 NVP: Waiting for prebuffer.. 0 AAAAAAAAAALdAALD 2006-09-16 03:36:01.461 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.461 AO: 58816 bytes free on soundcard 2006-09-16 03:36:01.471 AO: 60640 bytes free on soundcard 2006-09-16 03:36:01.472 AFD: video timecode 183637 183637 2040 2100 2006-09-16 03:36:01.474 AFD: audio timecode 189009 189009 2100 2124 2006-09-16 03:36:01.475 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=2100 2006-09-16 03:36:01.476 AFD: audio timecode 191169 191169 2124 2148 2006-09-16 03:36:01.477 AO: 61824 bytes free on soundcard 2006-09-16 03:36:01.479 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=2124 2006-09-16 03:36:01.490 AO: 60160 bytes free on soundcard 2006-09-16 03:36:01.490 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.994476, warpfactor_avg: 0.999995 2006-09-16 03:36:01.497 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.497 AO: Broadcasting free space avail 2006-09-16 03:36:01.497 AO: Broadcasting free space avail 2006-09-16 03:36:01.498 AO: Broadcasting free space avail 2006-09-16 03:36:01.498 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.498 AO: Broadcasting free space avail 2006-09-16 03:36:01.505 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.505 AO: Broadcasting free space avail 2006-09-16 03:36:01.513 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.513 AO: Broadcasting free space avail 2006-09-16 03:36:01.520 NVP: prebuffering pause 2006-09-16 03:36:01.520 NVP: Waiting for prebuffer.. 0 AAALAAAAAADDAALA 2006-09-16 03:36:01.521 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.521 AO: 53952 bytes free on soundcard 2006-09-16 03:36:01.522 AFD: video timecode 198037 187237 2080 2148 2006-09-16 03:36:01.525 AFD: audio timecode 193328 193328 2148 2172 2006-09-16 03:36:01.526 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2148 2006-09-16 03:36:01.527 AFD: audio timecode 195488 195488 2172 2196 2006-09-16 03:36:01.527 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2172 2006-09-16 03:36:01.529 AO: 55552 bytes free on soundcard 2006-09-16 03:36:01.538 AO: 57280 bytes free on soundcard 2006-09-16 03:36:01.546 AO: 58784 bytes free on soundcard 2006-09-16 03:36:01.555 NVP: A/V Divergence: -0.21875, Rate: -0.21875, Warpfactor: 0.994974, warpfactor_avg: 0.999994 2006-09-16 03:36:01.556 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.557 AO: Broadcasting free space avail 2006-09-16 03:36:01.557 AO: Broadcasting free space avail 2006-09-16 03:36:01.557 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:01.557 AO: Broadcasting free space avail 2006-09-16 03:36:01.560 NVP: prebuffering pause 2006-09-16 03:36:01.560 NVP: Waiting for prebuffer.. 0 LAALAAAAAAAAAAdA 2006-09-16 03:36:01.565 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.565 AO: 54144 bytes free on soundcard 2006-09-16 03:36:01.571 AFD: video timecode 190837 190837 2120 2196 2006-09-16 03:36:01.573 AO: 55712 bytes free on soundcard 2006-09-16 03:36:01.574 AFD: audio timecode 197648 197648 2196 2220 2006-09-16 03:36:01.574 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2196 2006-09-16 03:36:01.581 AO: 57216 bytes free on soundcard 2006-09-16 03:36:01.593 NVP: A/V Divergence: -0.3828, Rate: -0.16405, Warpfactor: 0.995471, warpfactor_avg: 0.999994 2006-09-16 03:36:01.594 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.594 AO: Broadcasting free space avail 2006-09-16 03:36:01.595 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.595 AO: Broadcasting free space avail 2006-09-16 03:36:01.601 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.601 AO: Broadcasting free space avail 2006-09-16 03:36:01.606 NVP: prebuffering pause 2006-09-16 03:36:01.606 NVP: Waiting for prebuffer.. 0 DAALAAAAAAAAALdA 2006-09-16 03:36:01.607 AFD: video timecode 194437 194437 2160 2220 2006-09-16 03:36:01.609 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.609 AO: 58592 bytes free on soundcard 2006-09-16 03:36:01.612 AFD: audio timecode 199808 199808 2220 2244 2006-09-16 03:36:01.612 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2220 2006-09-16 03:36:01.614 AFD: audio timecode 201968 201968 2244 2268 2006-09-16 03:36:01.614 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=2244 2006-09-16 03:36:01.617 AO: 60160 bytes free on soundcard 2006-09-16 03:36:01.625 AO: 61600 bytes free on soundcard 2006-09-16 03:36:01.633 AO: 58976 bytes free on soundcard 2006-09-16 03:36:01.639 NVP: A/V Divergence: -0.5496, Rate: -0.1668, Warpfactor: 0.994973, warpfactor_avg: 0.999994 2006-09-16 03:36:01.642 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.642 AO: Broadcasting free space avail 2006-09-16 03:36:01.643 AO: Broadcasting free space avail 2006-09-16 03:36:01.643 AO: Broadcasting free space avail 2006-09-16 03:36:01.643 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:01.643 AO: Broadcasting free space avail 2006-09-16 03:36:01.644 NVP: prebuffering pause 2006-09-16 03:36:01.644 NVP: Waiting for prebuffer.. 0 AAALAALAAAAAADDA 2006-09-16 03:36:01.649 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.650 AO: 49984 bytes free on soundcard 2006-09-16 03:36:01.657 AO: 51360 bytes free on soundcard 2006-09-16 03:36:01.661 AFD: video timecode 208837 198037 2200 2268 2006-09-16 03:36:01.662 AFD: audio timecode 204128 204128 2268 2292 2006-09-16 03:36:01.663 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2268 2006-09-16 03:36:01.667 AO: 53216 bytes free on soundcard 2006-09-16 03:36:01.675 AO: 54816 bytes free on soundcard 2006-09-16 03:36:01.668 AFD: audio timecode 206288 206288 2292 2316 2006-09-16 03:36:01.676 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=2292 2006-09-16 03:36:01.681 AO: 55936 bytes free on soundcard 2006-09-16 03:36:01.690 AO: 57760 bytes free on soundcard 2006-09-16 03:36:01.697 AO: 58976 bytes free on soundcard 2006-09-16 03:36:01.704 NVP: A/V Divergence: -0.5247, Rate: 0.0249, Warpfactor: 0.995471, warpfactor_avg: 0.999993 2006-09-16 03:36:01.705 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.708 AO: Broadcasting free space avail 2006-09-16 03:36:01.708 AO: Broadcasting free space avail 2006-09-16 03:36:01.708 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.708 AO: Broadcasting free space avail 2006-09-16 03:36:01.711 NVP: prebuffering pause 2006-09-16 03:36:01.711 NVP: Waiting for prebuffer.. 0 ALAdAALAAAAAAAAA 2006-09-16 03:36:01.716 AFD: video timecode 201637 201637 2240 2316 2006-09-16 03:36:01.717 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.718 AO: 54848 bytes free on soundcard 2006-09-16 03:36:01.719 AFD: audio timecode 208448 208448 2316 2340 2006-09-16 03:36:01.720 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2316 2006-09-16 03:36:01.729 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.729 AO: Broadcasting free space avail 2006-09-16 03:36:01.729 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.730 AO: Broadcasting free space avail 2006-09-16 03:36:01.728 NVP: A/V Divergence: -0.674775, Rate: -0.150075, Warpfactor: 0.994973, warpfactor_avg: 0.999993 2006-09-16 03:36:01.737 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.737 AO: Broadcasting free space avail 2006-09-16 03:36:01.746 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:01.747 AO: Broadcasting free space avail 2006-09-16 03:36:01.748 NVP: prebuffering pause 2006-09-16 03:36:01.748 NVP: Waiting for prebuffer.. 0 ADLdAALAAAAAAAAA 2006-09-16 03:36:01.753 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.753 AO: 57536 bytes free on soundcard 2006-09-16 03:36:01.761 AO: 58976 bytes free on soundcard 2006-09-16 03:36:01.761 AFD: video timecode 205237 205237 2280 2340 2006-09-16 03:36:01.763 AFD: audio timecode 210608 210608 2340 2364 2006-09-16 03:36:01.763 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2340 2006-09-16 03:36:01.767 AFD: audio timecode 212768 212768 2364 2388 2006-09-16 03:36:01.767 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2364 2006-09-16 03:36:01.769 AO: 60608 bytes free on soundcard 2006-09-16 03:36:01.779 AO: 62592 bytes free on soundcard 2006-09-16 03:36:01.785 AO: 59552 bytes free on soundcard 2006-09-16 03:36:01.794 AO: 61344 bytes free on soundcard 2006-09-16 03:36:01.801 AO: 62592 bytes free on soundcard 2006-09-16 03:36:01.805 NVP: A/V Divergence: -0.768575, Rate: -0.0938, Warpfactor: 0.994476, warpfactor_avg: 0.999993 2006-09-16 03:36:01.807 AFD: video timecode 219637 208837 2320 2388 2006-09-16 03:36:01.809 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.809 AO: Broadcasting free space avail 2006-09-16 03:36:01.812 AO: Broadcasting free space avail 2006-09-16 03:36:01.812 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:01.812 AO: Broadcasting free space avail 2006-09-16 03:36:01.817 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:01.818 AO: Broadcasting free space avail 2006-09-16 03:36:01.822 NVP: A/V Divergence: -0.763925, Rate: 0.00465, Warpfactor: 0.993978, warpfactor_avg: 0.999992 2006-09-16 03:36:01.808 AFD: audio timecode 214928 214928 2388 2412 2006-09-16 03:36:01.823 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2388 2006-09-16 03:36:01.825 AO: Broadcasting free space avail 2006-09-16 03:36:01.825 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:01.827 AO: Broadcasting free space avail 2006-09-16 03:36:01.824 AFD: audio timecode 217088 217088 2412 2436 2006-09-16 03:36:01.831 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2412 2006-09-16 03:36:01.833 AO: Broadcasting free space avail 2006-09-16 03:36:01.833 AO: Broadcasting free space avail 2006-09-16 03:36:01.834 AO: Broadcasting free space avail 2006-09-16 03:36:01.838 NVP: prebuffering pause 2006-09-16 03:36:01.838 NVP: Waiting for prebuffer.. 0 AAAAALdAALAAAAAA 2006-09-16 03:36:01.841 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:01.841 AO: 45760 bytes free on soundcard 2006-09-16 03:36:01.849 AO: 47264 bytes free on soundcard 2006-09-16 03:36:01.853 AFD: video timecode 212437 212437 2360 2436 2006-09-16 03:36:01.856 AFD: audio timecode 219248 219248 2436 2460 2006-09-16 03:36:01.857 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2436 2006-09-16 03:36:01.861 AO: 49504 bytes free on soundcard 2006-09-16 03:36:01.875 AO: 52224 bytes free on soundcard 2006-09-16 03:36:01.881 AFD: video timecode 216037 216037 2400 2460 2006-09-16 03:36:01.882 AFD: audio timecode 221408 221408 2460 2484 2006-09-16 03:36:01.883 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=2460 2006-09-16 03:36:01.884 AFD: audio timecode 223568 223568 2484 2508 2006-09-16 03:36:01.885 AO: 54112 bytes free on soundcard 2006-09-16 03:36:01.891 NVP: A/V Divergence: -0.635425, Rate: 0.1285, Warpfactor: 0.994475, warpfactor_avg: 0.999992 2006-09-16 03:36:01.884 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=2484 2006-09-16 03:36:01.897 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:01.897 AO: Broadcasting free space avail 2006-09-16 03:36:01.898 AO: Broadcasting free space avail 2006-09-16 03:36:01.898 AO: Broadcasting free space avail 2006-09-16 03:36:01.898 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.899 AO: Broadcasting free space avail 2006-09-16 03:36:01.913 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.913 AO: Broadcasting free space avail 2006-09-16 03:36:01.924 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:01.925 AO: Broadcasting free space avail 2006-09-16 03:36:01.925 AFD: video timecode 230437 219637 2440 2508 2006-09-16 03:36:01.928 AFD: audio timecode 225728 225728 2508 2532 2006-09-16 03:36:01.939 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2508 2006-09-16 03:36:01.941 AFD: audio timecode 227888 227888 2532 2556 2006-09-16 03:36:01.941 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=2532 2006-09-16 03:36:01.943 AO: Broadcasting free space avail 2006-09-16 03:36:01.944 AO: Broadcasting free space avail 2006-09-16 03:36:01.944 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.944 AO: Broadcasting free space avail 2006-09-16 03:36:01.949 NVP: A/V Divergence: -0.6828, Rate: -0.047375, Warpfactor: 0.994151, warpfactor_avg: 0.999992 2006-09-16 03:36:01.951 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.951 AO: Broadcasting free space avail 2006-09-16 03:36:01.966 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.967 AO: Broadcasting free space avail 2006-09-16 03:36:01.973 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:01.973 AO: Broadcasting free space avail 2006-09-16 03:36:01.979 AFD: video timecode 223237 223237 2480 2556 2006-09-16 03:36:01.980 AFD: audio timecode 230048 230048 2556 2580 2006-09-16 03:36:01.985 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2556 2006-09-16 03:36:01.993 AO: Broadcasting free space avail 2006-09-16 03:36:01.993 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:01.993 AO: Broadcasting free space avail 2006-09-16 03:36:02.010 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.011 AO: Broadcasting free space avail 2006-09-16 03:36:02.018 NVP: A/V Divergence: -0.91835, Rate: -0.23555, Warpfactor: 0.993653, warpfactor_avg: 0.999991 2006-09-16 03:36:02.019 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.020 AO: Broadcasting free space avail 2006-09-16 03:36:02.029 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.030 AO: Broadcasting free space avail 2006-09-16 03:36:02.037 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.037 AO: Broadcasting free space avail 2006-09-16 03:36:02.044 AFD: video timecode 226837 226837 2520 2580 2006-09-16 03:36:02.047 AFD: audio timecode 232208 232208 2580 2604 2006-09-16 03:36:02.048 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2580 2006-09-16 03:36:02.049 AO: Broadcasting free space avail 2006-09-16 03:36:02.050 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.050 AO: Broadcasting free space avail 2006-09-16 03:36:02.052 AFD: audio timecode 234368 234368 2604 2628 2006-09-16 03:36:02.052 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2604 2006-09-16 03:36:02.065 AO: Broadcasting free space avail 2006-09-16 03:36:02.065 AO: Broadcasting free space avail 2006-09-16 03:36:02.065 AO: Broadcasting free space avail 2006-09-16 03:36:02.074 AO: Broadcasting free space avail 2006-09-16 03:36:02.081 AFD: video timecode 241237 230437 2560 2628 2006-09-16 03:36:02.083 AFD: audio timecode 236528 236528 2628 2652 2006-09-16 03:36:02.083 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2628 2006-09-16 03:36:02.085 AO: Broadcasting free space avail 2006-09-16 03:36:02.086 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:02.086 AO: Broadcasting free space avail 2006-09-16 03:36:02.090 AFD: audio timecode 238688 238688 2652 2676 2006-09-16 03:36:02.091 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2652 2006-09-16 03:36:02.098 NVP: A/V Divergence: -1.13875, Rate: -0.2204, Warpfactor: 0.993157, warpfactor_avg: 0.999991 2006-09-16 03:36:02.100 AO: Broadcasting free space avail 2006-09-16 03:36:02.100 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.101 AO: Broadcasting free space avail 2006-09-16 03:36:02.121 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.122 AO: Broadcasting free space avail 2006-09-16 03:36:02.132 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.132 AO: Broadcasting free space avail 2006-09-16 03:36:02.133 AFD: video timecode 234037 234037 2600 2676 2006-09-16 03:36:02.134 AFD: audio timecode 240848 240848 2676 2700 2006-09-16 03:36:02.135 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=2676 2006-09-16 03:36:02.141 NVP: A/V Divergence: -1.56655, Rate: -0.4278, Warpfactor: 0.99266, warpfactor_avg: 0.99999 2006-09-16 03:36:02.142 AO: Broadcasting free space avail 2006-09-16 03:36:02.142 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.143 AO: Broadcasting free space avail 2006-09-16 03:36:02.154 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.154 AO: Broadcasting free space avail 2006-09-16 03:36:02.161 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.161 AO: Broadcasting free space avail 2006-09-16 03:36:02.169 AFD: video timecode 237637 237637 2640 2700 2006-09-16 03:36:02.171 AFD: audio timecode 243008 243008 2700 2724 2006-09-16 03:36:02.171 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2700 2006-09-16 03:36:02.173 AO: Broadcasting free space avail 2006-09-16 03:36:02.174 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.174 AO: Broadcasting free space avail 2006-09-16 03:36:02.184 AFD: audio timecode 245168 245168 2724 2748 2006-09-16 03:36:02.185 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2724 2006-09-16 03:36:02.197 AO: Broadcasting free space avail 2006-09-16 03:36:02.197 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:02.197 AO: Broadcasting free space avail 2006-09-16 03:36:02.197 NVP: A/V Divergence: -1.9999, Rate: -0.43335, Warpfactor: 0.992164, warpfactor_avg: 0.99999 2006-09-16 03:36:02.214 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:02.214 AO: Broadcasting free space avail 2006-09-16 03:36:02.221 AFD: video timecode 252036 241237 2680 2748 2006-09-16 03:36:02.222 AFD: audio timecode 247328 247328 2748 2772 2006-09-16 03:36:02.223 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2748 2006-09-16 03:36:02.225 AO: Broadcasting free space avail 2006-09-16 03:36:02.225 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.225 AO: Broadcasting free space avail 2006-09-16 03:36:02.224 AFD: audio timecode 249488 249488 2772 2796 2006-09-16 03:36:02.226 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2772 2006-09-16 03:36:02.237 AO: Broadcasting free space avail 2006-09-16 03:36:02.237 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.238 AO: Broadcasting free space avail 2006-09-16 03:36:02.243 NVP: A/V Divergence: -2.49992, Rate: -0.500025, Warpfactor: 0.991668, warpfactor_avg: 0.999989 2006-09-16 03:36:02.245 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.246 AO: Broadcasting free space avail 2006-09-16 03:36:02.256 NVP: A/V Divergence: -2.73743, Rate: -0.2375, Warpfactor: 0.991172, warpfactor_avg: 0.999988 2006-09-16 03:36:02.260 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.260 AO: Broadcasting free space avail 2006-09-16 03:36:02.279 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.279 AO: Broadcasting free space avail 2006-09-16 03:36:02.286 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.286 AO: Broadcasting free space avail 2006-09-16 03:36:02.289 AFD: video timecode 244837 244837 2720 2796 2006-09-16 03:36:02.296 NVP: A/V Divergence: -2.76555, Rate: -0.028125, Warpfactor: 0.990676, warpfactor_avg: 0.999988 2006-09-16 03:36:02.302 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.302 AO: Broadcasting free space avail 2006-09-16 03:36:02.300 AFD: audio timecode 251648 251648 2796 2820 2006-09-16 03:36:02.303 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2796 2006-09-16 03:36:02.314 AO: Broadcasting free space avail 2006-09-16 03:36:02.315 AO: Broadcasting free space avail 2006-09-16 03:36:02.315 AO: Broadcasting free space avail 2006-09-16 03:36:02.320 NVP: A/V Divergence: -2.71165, Rate: 0.0539, Warpfactor: 0.990181, warpfactor_avg: 0.999987 2006-09-16 03:36:02.322 AO: Broadcasting free space avail 2006-09-16 03:36:02.334 AO: Broadcasting free space avail 2006-09-16 03:36:02.341 AO: Broadcasting free space avail 2006-09-16 03:36:02.347 AFD: video timecode 248437 248437 2760 2820 2006-09-16 03:36:02.348 AFD: audio timecode 253808 253808 2820 2844 2006-09-16 03:36:02.357 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2820 2006-09-16 03:36:02.359 AFD: audio timecode 255968 255968 2844 2868 2006-09-16 03:36:02.359 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=2844 2006-09-16 03:36:02.365 AO: Broadcasting free space avail 2006-09-16 03:36:02.365 AO: Broadcasting free space avail 2006-09-16 03:36:02.366 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.366 AO: Broadcasting free space avail 2006-09-16 03:36:02.377 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.380 AO: Broadcasting free space avail 2006-09-16 03:36:02.390 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.390 AO: Broadcasting free space avail 2006-09-16 03:36:02.397 AFD: video timecode 262836 252036 2800 2868 2006-09-16 03:36:02.401 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.401 AO: Broadcasting free space avail 2006-09-16 03:36:02.400 AFD: audio timecode 258128 258128 2868 2892 2006-09-16 03:36:02.405 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=2868 2006-09-16 03:36:02.407 AFD: audio timecode 260288 260288 2892 2916 2006-09-16 03:36:02.407 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=2892 2006-09-16 03:36:02.409 NVP: A/V Divergence: -2.56498, Rate: 0.146675, Warpfactor: 0.989686, warpfactor_avg: 0.999987 2006-09-16 03:36:02.410 AO: Broadcasting free space avail 2006-09-16 03:36:02.411 AO: Broadcasting free space avail 2006-09-16 03:36:02.411 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.411 AO: Broadcasting free space avail 2006-09-16 03:36:02.417 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.418 AO: Broadcasting free space avail 2006-09-16 03:36:02.431 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.431 AO: Broadcasting free space avail 2006-09-16 03:36:02.437 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.437 AO: Broadcasting free space avail 2006-09-16 03:36:02.446 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.447 AO: Broadcasting free space avail 2006-09-16 03:36:02.448 AFD: video timecode 255636 255636 2840 2916 2006-09-16 03:36:02.450 AFD: audio timecode 262448 262448 2916 2940 2006-09-16 03:36:02.451 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2916 2006-09-16 03:36:02.458 NVP: A/V Divergence: -2.57372, Rate: -0.00875, Warpfactor: 0.989191, warpfactor_avg: 0.999986 2006-09-16 03:36:02.459 AO: Broadcasting free space avail 2006-09-16 03:36:02.459 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:02.460 AO: Broadcasting free space avail 2006-09-16 03:36:02.470 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:02.471 AO: Broadcasting free space avail 2006-09-16 03:36:02.482 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:02.483 AO: Broadcasting free space avail 2006-09-16 03:36:02.489 AFD: video timecode 259236 259236 2880 2940 2006-09-16 03:36:02.492 AFD: audio timecode 264607 264607 2940 2964 2006-09-16 03:36:02.495 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2940 2006-09-16 03:36:02.495 AO: audio waiting for buffer to fill: have 7168 want 4096 2006-09-16 03:36:02.499 AO: Broadcasting free space avail 2006-09-16 03:36:02.500 AFD: audio timecode 266767 266767 2964 2988 2006-09-16 03:36:02.501 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=2964 2006-09-16 03:36:02.512 AO: Broadcasting free space avail 2006-09-16 03:36:02.513 AO: Broadcasting free space avail 2006-09-16 03:36:02.513 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.513 AO: Broadcasting free space avail 2006-09-16 03:36:02.522 NVP: A/V Divergence: -2.78028, Rate: -0.20655, Warpfactor: 0.988696, warpfactor_avg: 0.999985 2006-09-16 03:36:02.523 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.523 AO: Broadcasting free space avail 2006-09-16 03:36:02.537 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.537 AO: Broadcasting free space avail 2006-09-16 03:36:02.548 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.549 AO: Broadcasting free space avail 2006-09-16 03:36:02.549 AFD: video timecode 273636 262836 2920 2988 2006-09-16 03:36:02.551 AFD: audio timecode 268927 268927 2988 3012 2006-09-16 03:36:02.551 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2988 2006-09-16 03:36:02.556 AFD: audio timecode 271087 271087 3012 3036 2006-09-16 03:36:02.556 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3012 2006-09-16 03:36:02.561 AO: Broadcasting free space avail 2006-09-16 03:36:02.562 AO: Broadcasting free space avail 2006-09-16 03:36:02.575 AO: Broadcasting free space avail 2006-09-16 03:36:02.576 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:02.576 AO: Broadcasting free space avail 2006-09-16 03:36:02.584 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:02.585 AO: Broadcasting free space avail 2006-09-16 03:36:02.585 AFD: video timecode 266436 266436 2960 3036 2006-09-16 03:36:02.587 AFD: audio timecode 273247 273247 3036 3060 2006-09-16 03:36:02.587 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3036 2006-09-16 03:36:02.604 AO: Broadcasting free space avail 2006-09-16 03:36:02.604 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:02.613 AO: Broadcasting free space avail 2006-09-16 03:36:02.617 AFD: video timecode 270036 270036 3000 3060 2006-09-16 03:36:02.619 AFD: audio timecode 275407 275407 3060 3084 2006-09-16 03:36:02.620 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3060 2006-09-16 03:36:02.622 AFD: audio timecode 277567 277567 3084 3108 2006-09-16 03:36:02.623 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=3084 2006-09-16 03:36:02.629 NVP: A/V Divergence: -2.9977, Rate: -0.217425, Warpfactor: 0.988202, warpfactor_avg: 0.999984 2006-09-16 03:36:02.635 AO: Broadcasting free space avail 2006-09-16 03:36:02.635 AO: Broadcasting free space avail 2006-09-16 03:36:02.636 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.636 AO: Broadcasting free space avail 2006-09-16 03:36:02.645 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.645 AO: Broadcasting free space avail 2006-09-16 03:36:02.651 NVP: A/V Divergence: -3.29827, Rate: -0.300575, Warpfactor: 0.987708, warpfactor_avg: 0.999983 2006-09-16 03:36:02.653 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.653 AO: Broadcasting free space avail 2006-09-16 03:36:02.653 NVP: Video is 3.29827 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:36:02.664 NVP: A/V Divergence: -3.35495, Rate: -0.056675, Warpfactor: 0.987214, warpfactor_avg: 0.999983 2006-09-16 03:36:02.666 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.667 AO: Broadcasting free space avail 2006-09-16 03:36:02.669 NVP: Video is 3.35495 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:36:02.684 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.684 AO: Broadcasting free space avail 2006-09-16 03:36:02.695 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.696 AO: Broadcasting free space avail 2006-09-16 03:36:02.700 AFD: video timecode 284436 273636 3040 3108 2006-09-16 03:36:02.704 NVP: A/V Divergence: -3.2412, Rate: 0.11375, Warpfactor: 0.98672, warpfactor_avg: 0.999982 2006-09-16 03:36:02.708 AFD: audio timecode 279727 279727 3108 3132 2006-09-16 03:36:02.708 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3108 2006-09-16 03:36:02.710 AFD: audio timecode 281887 281887 3132 3156 2006-09-16 03:36:02.710 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=3132 2006-09-16 03:36:02.712 AO: Broadcasting free space avail 2006-09-16 03:36:02.716 AO: Broadcasting free space avail 2006-09-16 03:36:02.716 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.716 AO: Broadcasting free space avail 2006-09-16 03:36:02.717 NVP: Video is 3.2412 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:36:02.737 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.737 AO: Broadcasting free space avail 2006-09-16 03:36:02.748 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.749 AO: Broadcasting free space avail 2006-09-16 03:36:02.753 AFD: video timecode 277236 277236 3080 3156 2006-09-16 03:36:02.759 AFD: audio timecode 284047 284047 3156 3180 2006-09-16 03:36:02.759 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3156 2006-09-16 03:36:02.762 AO: Broadcasting free space avail 2006-09-16 03:36:02.763 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.763 AO: Broadcasting free space avail 2006-09-16 03:36:02.768 NVP: A/V Divergence: -3.07465, Rate: 0.16655, Warpfactor: 0.986227, warpfactor_avg: 0.999981 2006-09-16 03:36:02.770 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.770 AO: Broadcasting free space avail 2006-09-16 03:36:02.785 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.785 AO: Broadcasting free space avail 2006-09-16 03:36:02.789 NVP: Video is 3.07465 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:36:02.793 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.793 AO: Broadcasting free space avail 2006-09-16 03:36:02.793 AFD: video timecode 280836 280836 3120 3180 2006-09-16 03:36:02.795 AFD: audio timecode 286207 286207 3180 3204 2006-09-16 03:36:02.795 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3180 2006-09-16 03:36:02.797 AFD: audio timecode 288367 288367 3204 3228 2006-09-16 03:36:02.797 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3204 2006-09-16 03:36:02.806 AO: Broadcasting free space avail 2006-09-16 03:36:02.806 AO: Broadcasting free space avail 2006-09-16 03:36:02.806 AO: Broadcasting free space avail 2006-09-16 03:36:02.807 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:02.807 AO: Broadcasting free space avail 2006-09-16 03:36:02.809 NVP: A/V Divergence: -3.09348, Rate: -0.018825, Warpfactor: 0.985734, warpfactor_avg: 0.99998 2006-09-16 03:36:02.821 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:02.821 AO: Broadcasting free space avail 2006-09-16 03:36:02.826 NVP: Video is 3.09348 frames behind audio (too slow), skipping A/V wait. 2006-09-16 03:36:02.829 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:02.829 AO: Broadcasting free space avail 2006-09-16 03:36:02.837 AFD: video timecode 295236 284436 3160 3228 2006-09-16 03:36:02.840 AFD: audio timecode 290527 290527 3228 3252 2006-09-16 03:36:02.840 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3228 2006-09-16 03:36:02.846 AFD: audio timecode 292687 292687 3252 3276 2006-09-16 03:36:02.847 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=3252 2006-09-16 03:36:02.852 NVP: A/V Divergence: -2.9576, Rate: 0.135875, Warpfactor: 0.985241, warpfactor_avg: 0.999979 2006-09-16 03:36:02.853 AO: Broadcasting free space avail 2006-09-16 03:36:02.853 AO: Broadcasting free space avail 2006-09-16 03:36:02.853 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.853 AO: Broadcasting free space avail 2006-09-16 03:36:02.859 NVP: A/V Divergence: -2.7807, Rate: 0.1769, Warpfactor: 0.984748, warpfactor_avg: 0.999978 2006-09-16 03:36:02.866 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.867 AO: Broadcasting free space avail 2006-09-16 03:36:02.878 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.879 AO: Broadcasting free space avail 2006-09-16 03:36:02.888 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:02.888 AO: Broadcasting free space avail 2006-09-16 03:36:02.889 AFD: video timecode 288036 288036 3200 3276 2006-09-16 03:36:02.891 AFD: audio timecode 294847 294847 3276 3300 2006-09-16 03:36:02.891 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3276 2006-09-16 03:36:02.905 AO: Broadcasting free space avail 2006-09-16 03:36:02.906 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.906 AO: Broadcasting free space avail 2006-09-16 03:36:02.896 NVP: A/V Divergence: -2.60427, Rate: 0.176425, Warpfactor: 0.984256, warpfactor_avg: 0.999977 2006-09-16 03:36:02.915 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:02.916 AO: Broadcasting free space avail 2006-09-16 03:36:02.922 AFD: video timecode 291636 291636 3240 3300 2006-09-16 03:36:02.925 AFD: audio timecode 297007 297007 3300 3324 2006-09-16 03:36:02.926 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3300 2006-09-16 03:36:02.927 AFD: audio timecode 299167 299167 3324 3348 2006-09-16 03:36:02.927 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=3324 2006-09-16 03:36:02.929 AO: Broadcasting free space avail 2006-09-16 03:36:02.929 AO: Broadcasting free space avail 2006-09-16 03:36:02.929 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.930 AO: Broadcasting free space avail 2006-09-16 03:36:02.936 NVP: A/V Divergence: -2.5032, Rate: 0.101075, Warpfactor: 0.983764, warpfactor_avg: 0.999976 2006-09-16 03:36:02.941 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.942 AO: Broadcasting free space avail 2006-09-16 03:36:02.954 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.955 AO: Broadcasting free space avail 2006-09-16 03:36:02.963 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:02.964 AO: Broadcasting free space avail 2006-09-16 03:36:02.972 AFD: video timecode 306036 295236 3280 3348 2006-09-16 03:36:02.973 AFD: audio timecode 301327 301327 3348 3372 2006-09-16 03:36:02.974 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3348 2006-09-16 03:36:02.977 AO: Broadcasting free space avail 2006-09-16 03:36:02.977 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:02.978 AO: Broadcasting free space avail 2006-09-16 03:36:02.982 AFD: audio timecode 303487 303487 3372 3396 2006-09-16 03:36:02.982 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3372 2006-09-16 03:36:02.989 NVP: A/V Divergence: -2.37115, Rate: 0.13205, Warpfactor: 0.983272, warpfactor_avg: 0.999975 2006-09-16 03:36:02.990 AO: Broadcasting free space avail 2006-09-16 03:36:02.990 AO: Broadcasting free space avail 2006-09-16 03:36:02.990 AO: Broadcasting free space avail 2006-09-16 03:36:02.994 NVP: prebuffering pause 2006-09-16 03:36:02.994 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAdAALAL 2006-09-16 03:36:03.001 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:03.001 AO: 57888 bytes free on soundcard 2006-09-16 03:36:03.011 AO: 59744 bytes free on soundcard 2006-09-16 03:36:03.017 AFD: video timecode 298836 298836 3320 3396 2006-09-16 03:36:03.018 AFD: audio timecode 305647 305647 3396 3420 2006-09-16 03:36:03.019 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=3396 2006-09-16 03:36:03.025 AO: 62400 bytes free on soundcard 2006-09-16 03:36:03.036 AO: 60544 bytes free on soundcard 2006-09-16 03:36:03.041 AFD: video timecode 302436 302436 3360 3420 2006-09-16 03:36:03.043 AFD: audio timecode 307807 307807 3420 3444 2006-09-16 03:36:03.043 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=3420 2006-09-16 03:36:03.048 NVP: A/V Divergence: -2.2096, Rate: 0.16155, Warpfactor: 0.983591, warpfactor_avg: 0.999974 2006-09-16 03:36:03.051 AFD: audio timecode 309967 309967 3444 3468 2006-09-16 03:36:03.051 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=3444 2006-09-16 03:36:03.054 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:03.054 AO: Broadcasting free space avail 2006-09-16 03:36:03.055 AO: Broadcasting free space avail 2006-09-16 03:36:03.055 AO: Broadcasting free space avail 2006-09-16 03:36:03.055 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:03.055 AO: Broadcasting free space avail 2006-09-16 03:36:03.069 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:03.069 AO: Broadcasting free space avail 2006-09-16 03:36:03.077 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:03.078 AO: Broadcasting free space avail 2006-09-16 03:36:03.081 AFD: video timecode 316836 306036 3400 3468 2006-09-16 03:36:03.082 AFD: audio timecode 312127 312127 3468 3492 2006-09-16 03:36:03.083 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3468 2006-09-16 03:36:03.084 AFD: audio timecode 314287 314287 3492 3516 2006-09-16 03:36:03.085 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=3492 2006-09-16 03:36:03.093 AO: Broadcasting free space avail 2006-09-16 03:36:03.094 AO: Broadcasting free space avail 2006-09-16 03:36:03.094 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:03.094 AO: Broadcasting free space avail 2006-09-16 03:36:03.103 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:03.103 AO: Broadcasting free space avail 2006-09-16 03:36:03.112 AFD: video timecode 309636 309636 3440 3516 2006-09-16 03:36:03.115 AFD: audio timecode 316447 316447 3516 3540 2006-09-16 03:36:03.116 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=3516 2006-09-16 03:36:03.117 AO: Broadcasting free space avail 2006-09-16 03:36:03.117 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.117 AO: Broadcasting free space avail 2006-09-16 03:36:03.123 NVP: A/V Divergence: -2.4822, Rate: -0.2726, Warpfactor: 0.983099, warpfactor_avg: 0.999972 2006-09-16 03:36:03.125 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.126 AO: Broadcasting free space avail 2006-09-16 03:36:03.133 NVP: A/V Divergence: -2.5054, Rate: -0.0232, Warpfactor: 0.982607, warpfactor_avg: 0.999971 2006-09-16 03:36:03.134 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.134 AO: Broadcasting free space avail 2006-09-16 03:36:03.143 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.143 AO: Broadcasting free space avail 2006-09-16 03:36:03.148 NVP: A/V Divergence: -2.36655, Rate: 0.13885, Warpfactor: 0.98215, warpfactor_avg: 0.99997 2006-09-16 03:36:03.149 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.150 AO: Broadcasting free space avail 2006-09-16 03:36:03.153 NVP: prebuffering pause 2006-09-16 03:36:03.154 NVP: Waiting for prebuffer.. 0 LADLAAAAAAAAAdAA 2006-09-16 03:36:03.162 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:03.162 AO: 60096 bytes free on soundcard 2006-09-16 03:36:03.169 AO: 61536 bytes free on soundcard 2006-09-16 03:36:03.179 AFD: video timecode 313236 313236 3480 3540 2006-09-16 03:36:03.181 AFD: audio timecode 318607 318607 3540 3564 2006-09-16 03:36:03.181 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3540 2006-09-16 03:36:03.183 AFD: audio timecode 320767 320767 3564 3588 2006-09-16 03:36:03.183 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=3564 2006-09-16 03:36:03.185 AO: 60384 bytes free on soundcard 2006-09-16 03:36:03.201 AO: 63488 bytes free on soundcard 2006-09-16 03:36:03.209 AO: 60896 bytes free on soundcard 2006-09-16 03:36:03.211 AFD: video timecode 327636 316836 3520 3588 2006-09-16 03:36:03.215 AFD: audio timecode 322927 322927 3588 3612 2006-09-16 03:36:03.215 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=3588 2006-09-16 03:36:03.216 AFD: audio timecode 325087 325087 3612 3636 2006-09-16 03:36:03.217 AO: _AddSamples bytes=4608, used=16897, free=751103, timecode=3612 2006-09-16 03:36:03.225 NVP: A/V Divergence: -2.0624, Rate: 0.30415, Warpfactor: 0.982641, warpfactor_avg: 0.999969 2006-09-16 03:36:03.226 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:03.226 AO: Broadcasting free space avail 2006-09-16 03:36:03.227 AO: Broadcasting free space avail 2006-09-16 03:36:03.227 AO: Broadcasting free space avail 2006-09-16 03:36:03.227 AO: Broadcasting free space avail 2006-09-16 03:36:03.228 AO: Broadcasting free space avail 2006-09-16 03:36:03.228 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.230 AO: Broadcasting free space avail 2006-09-16 03:36:03.237 NVP: A/V Divergence: -1.8218, Rate: 0.2406, Warpfactor: 0.983132, warpfactor_avg: 0.999968 2006-09-16 03:36:03.238 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.238 AO: Broadcasting free space avail 2006-09-16 03:36:03.252 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.252 AO: Broadcasting free space avail 2006-09-16 03:36:03.258 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.259 AO: Broadcasting free space avail 2006-09-16 03:36:03.261 AFD: video timecode 320436 320436 3560 3636 2006-09-16 03:36:03.265 AFD: audio timecode 327247 327247 3636 3660 2006-09-16 03:36:03.271 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3636 2006-09-16 03:36:03.279 AO: Broadcasting free space avail 2006-09-16 03:36:03.279 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:03.279 AO: Broadcasting free space avail 2006-09-16 03:36:03.280 NVP: A/V Divergence: -1.55385, Rate: 0.26795, Warpfactor: 0.983624, warpfactor_avg: 0.999967 2006-09-16 03:36:03.292 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:03.293 AO: Broadcasting free space avail 2006-09-16 03:36:03.297 AFD: video timecode 324036 324036 3600 3660 2006-09-16 03:36:03.306 AFD: audio timecode 329406 329406 3660 3684 2006-09-16 03:36:03.306 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3660 2006-09-16 03:36:03.308 AFD: audio timecode 331566 331566 3684 3708 2006-09-16 03:36:03.308 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=3684 2006-09-16 03:36:03.309 AO: Broadcasting free space avail 2006-09-16 03:36:03.309 AO: Broadcasting free space avail 2006-09-16 03:36:03.309 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:03.310 AO: Broadcasting free space avail 2006-09-16 03:36:03.320 NVP: A/V Divergence: -1.44662, Rate: 0.107225, Warpfactor: 0.984116, warpfactor_avg: 0.999966 2006-09-16 03:36:03.324 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:03.328 AO: Broadcasting free space avail 2006-09-16 03:36:03.330 NVP: prebuffering pause 2006-09-16 03:36:03.330 NVP: Waiting for prebuffer.. 0 ADADLAAALAAAAAAA 2006-09-16 03:36:03.341 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:03.342 AO: 53664 bytes free on soundcard 2006-09-16 03:36:03.351 AO: 55424 bytes free on soundcard 2006-09-16 03:36:03.357 AFD: video timecode 338436 327636 3640 3708 2006-09-16 03:36:03.359 AFD: audio timecode 333726 333726 3708 3732 2006-09-16 03:36:03.359 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=3708 2006-09-16 03:36:03.362 AFD: audio timecode 335886 335886 3732 3756 2006-09-16 03:36:03.363 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=3732 2006-09-16 03:36:03.365 AO: 58080 bytes free on soundcard 2006-09-16 03:36:03.378 AO: 60576 bytes free on soundcard 2006-09-16 03:36:03.385 AFD: video timecode 331236 331236 3680 3756 2006-09-16 03:36:03.387 AFD: audio timecode 338046 338046 3756 3780 2006-09-16 03:36:03.387 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=3756 2006-09-16 03:36:03.389 AO: 62720 bytes free on soundcard 2006-09-16 03:36:03.398 NVP: A/V Divergence: -1.20995, Rate: 0.236675, Warpfactor: 0.984608, warpfactor_avg: 0.999965 2006-09-16 03:36:03.399 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:03.400 AO: Broadcasting free space avail 2006-09-16 03:36:03.400 AO: Broadcasting free space avail 2006-09-16 03:36:03.400 AO: Broadcasting free space avail 2006-09-16 03:36:03.401 AO: Broadcasting free space avail 2006-09-16 03:36:03.401 AO: Broadcasting free space avail 2006-09-16 03:36:03.405 NVP: A/V Divergence: -1.10745, Rate: 0.1025, Warpfactor: 0.9851, warpfactor_avg: 0.999964 2006-09-16 03:36:03.417 AO: Broadcasting free space avail 2006-09-16 03:36:03.428 AO: Broadcasting free space avail 2006-09-16 03:36:03.429 AFD: video timecode 334836 334836 3720 3780 2006-09-16 03:36:03.431 AFD: audio timecode 340206 340206 3780 3804 2006-09-16 03:36:03.432 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=3780 2006-09-16 03:36:03.435 AFD: audio timecode 342366 342366 3804 3828 2006-09-16 03:36:03.436 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=3804 2006-09-16 03:36:03.438 AO: Broadcasting free space avail 2006-09-16 03:36:03.439 AO: Broadcasting free space avail 2006-09-16 03:36:03.439 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.439 AO: Broadcasting free space avail 2006-09-16 03:36:03.446 NVP: A/V Divergence: -1.01807, Rate: 0.089375, Warpfactor: 0.985593, warpfactor_avg: 0.999963 2006-09-16 03:36:03.449 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.449 AO: Broadcasting free space avail 2006-09-16 03:36:03.463 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.463 AO: Broadcasting free space avail 2006-09-16 03:36:03.473 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:03.473 AO: Broadcasting free space avail 2006-09-16 03:36:03.477 AFD: video timecode 349236 338436 3760 3828 2006-09-16 03:36:03.479 AFD: audio timecode 344526 344526 3828 3852 2006-09-16 03:36:03.480 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3828 2006-09-16 03:36:03.482 AFD: audio timecode 346686 346686 3852 3876 2006-09-16 03:36:03.483 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=3852 2006-09-16 03:36:03.486 AO: Broadcasting free space avail 2006-09-16 03:36:03.486 AO: Broadcasting free space avail 2006-09-16 03:36:03.486 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:03.487 AO: Broadcasting free space avail 2006-09-16 03:36:03.491 NVP: A/V Divergence: -0.9948, Rate: 0.023275, Warpfactor: 0.986085, warpfactor_avg: 0.999962 2006-09-16 03:36:03.497 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:03.497 AO: Broadcasting free space avail 2006-09-16 03:36:03.511 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:03.511 AO: Broadcasting free space avail 2006-09-16 03:36:03.517 AFD: video timecode 342036 342036 3800 3876 2006-09-16 03:36:03.521 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:03.521 AO: Broadcasting free space avail 2006-09-16 03:36:03.520 AFD: audio timecode 348846 348846 3876 3900 2006-09-16 03:36:03.523 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3876 2006-09-16 03:36:03.531 NVP: A/V Divergence: -0.96485, Rate: 0.02995, Warpfactor: 0.986578, warpfactor_avg: 0.999961 2006-09-16 03:36:03.538 AO: Broadcasting free space avail 2006-09-16 03:36:03.538 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:03.538 AO: Broadcasting free space avail 2006-09-16 03:36:03.548 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:03.549 AO: Broadcasting free space avail 2006-09-16 03:36:03.553 AFD: video timecode 345636 345636 3840 3900 2006-09-16 03:36:03.555 AFD: audio timecode 351006 351006 3900 3924 2006-09-16 03:36:03.555 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=3900 2006-09-16 03:36:03.557 AFD: audio timecode 353166 353166 3924 3948 2006-09-16 03:36:03.557 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=3924 2006-09-16 03:36:03.560 AO: Broadcasting free space avail 2006-09-16 03:36:03.560 AO: Broadcasting free space avail 2006-09-16 03:36:03.560 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:03.564 AO: Broadcasting free space avail 2006-09-16 03:36:03.570 NVP: A/V Divergence: -0.948625, Rate: 0.016225, Warpfactor: 0.987072, warpfactor_avg: 0.99996 2006-09-16 03:36:03.585 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:03.585 AO: Broadcasting free space avail 2006-09-16 03:36:03.589 NVP: prebuffering pause 2006-09-16 03:36:03.590 NVP: Waiting for prebuffer.. 0 AAAAAAADDAALAAAL 2006-09-16 03:36:03.590 AFD: video timecode 360035 349236 3880 3948 2006-09-16 03:36:03.595 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:03.595 AO: 53184 bytes free on soundcard 2006-09-16 03:36:03.602 AO: 54496 bytes free on soundcard 2006-09-16 03:36:03.603 AFD: audio timecode 355326 355326 3948 3972 2006-09-16 03:36:03.604 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3948 2006-09-16 03:36:03.606 AFD: audio timecode 357486 357486 3972 3996 2006-09-16 03:36:03.606 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3972 2006-09-16 03:36:03.609 AO: 55808 bytes free on soundcard 2006-09-16 03:36:03.622 AO: 58080 bytes free on soundcard 2006-09-16 03:36:03.628 NVP: A/V Divergence: -0.8302, Rate: 0.118425, Warpfactor: 0.987565, warpfactor_avg: 0.999959 2006-09-16 03:36:03.629 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:03.630 AO: Broadcasting free space avail 2006-09-16 03:36:03.631 AO: Broadcasting free space avail 2006-09-16 03:36:03.631 AO: Broadcasting free space avail 2006-09-16 03:36:03.631 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:03.631 AO: Broadcasting free space avail 2006-09-16 03:36:03.639 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:03.640 AO: Broadcasting free space avail 2006-09-16 03:36:03.646 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:03.646 AO: Broadcasting free space avail 2006-09-16 03:36:03.647 NVP: prebuffering pause 2006-09-16 03:36:03.647 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAdAALL 2006-09-16 03:36:03.655 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:03.656 AO: 52512 bytes free on soundcard 2006-09-16 03:36:03.656 AFD: video timecode 352835 352835 3920 3996 2006-09-16 03:36:03.658 AFD: audio timecode 359646 359646 3996 4020 2006-09-16 03:36:03.658 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3996 2006-09-16 03:36:03.669 AO: 55008 bytes free on soundcard 2006-09-16 03:36:03.680 AO: 57248 bytes free on soundcard 2006-09-16 03:36:03.685 AFD: video timecode 356435 356435 3960 4020 2006-09-16 03:36:03.690 AFD: audio timecode 361806 361806 4020 4044 2006-09-16 03:36:03.691 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=4020 2006-09-16 03:36:03.692 AFD: audio timecode 363966 363966 4044 4068 2006-09-16 03:36:03.692 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=4044 2006-09-16 03:36:03.696 NVP: A/V Divergence: -0.8539, Rate: -0.0237, Warpfactor: 0.988059, warpfactor_avg: 0.999958 2006-09-16 03:36:03.698 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:03.713 AO: Broadcasting free space avail 2006-09-16 03:36:03.714 AO: Broadcasting free space avail 2006-09-16 03:36:03.714 AO: Broadcasting free space avail 2006-09-16 03:36:03.714 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:03.714 AO: Broadcasting free space avail 2006-09-16 03:36:03.726 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:03.727 AO: Broadcasting free space avail 2006-09-16 03:36:03.729 AFD: video timecode 370835 360035 4000 4068 2006-09-16 03:36:03.730 AFD: audio timecode 366126 366126 4068 4092 2006-09-16 03:36:03.731 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=4068 2006-09-16 03:36:03.735 AFD: audio timecode 368286 368286 4092 4116 2006-09-16 03:36:03.735 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=4092 2006-09-16 03:36:03.737 AO: Broadcasting free space avail 2006-09-16 03:36:03.737 AO: Broadcasting free space avail 2006-09-16 03:36:03.738 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.740 AO: Broadcasting free space avail 2006-09-16 03:36:03.748 NVP: A/V Divergence: -1.23417, Rate: -0.380275, Warpfactor: 0.987565, warpfactor_avg: 0.999958 2006-09-16 03:36:03.749 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.750 AO: Broadcasting free space avail 2006-09-16 03:36:03.763 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.763 AO: Broadcasting free space avail 2006-09-16 03:36:03.769 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.769 AO: Broadcasting free space avail 2006-09-16 03:36:03.775 AFD: video timecode 363635 363635 4040 4116 2006-09-16 03:36:03.782 AFD: audio timecode 370446 370446 4116 4140 2006-09-16 03:36:03.783 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=4116 2006-09-16 03:36:03.793 AO: Broadcasting free space avail 2006-09-16 03:36:03.793 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:03.793 AO: Broadcasting free space avail 2006-09-16 03:36:03.780 NVP: A/V Divergence: -1.41313, Rate: -0.17895, Warpfactor: 0.987221, warpfactor_avg: 0.999957 2006-09-16 03:36:03.805 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:03.805 AO: Broadcasting free space avail 2006-09-16 03:36:03.813 AFD: video timecode 367235 367235 4080 4140 2006-09-16 03:36:03.815 AFD: audio timecode 372606 372606 4140 4164 2006-09-16 03:36:03.815 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=4140 2006-09-16 03:36:03.817 AO: Broadcasting free space avail 2006-09-16 03:36:03.817 AO: Broadcasting free space avail 2006-09-16 03:36:03.818 AO: Broadcasting free space avail 2006-09-16 03:36:03.819 AFD: audio timecode 374766 374766 4164 4188 2006-09-16 03:36:03.820 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=4164 2006-09-16 03:36:03.828 NVP: A/V Divergence: -1.64733, Rate: -0.2342, Warpfactor: 0.986728, warpfactor_avg: 0.999956 2006-09-16 03:36:03.829 AO: Broadcasting free space avail 2006-09-16 03:36:03.829 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:03.829 AO: Broadcasting free space avail 2006-09-16 03:36:03.834 NVP: A/V Divergence: -1.61047, Rate: 0.03685, Warpfactor: 0.987221, warpfactor_avg: 0.999955 2006-09-16 03:36:03.845 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:03.845 AO: Broadcasting free space avail 2006-09-16 03:36:03.853 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:03.853 AO: Broadcasting free space avail 2006-09-16 03:36:03.857 AFD: video timecode 381635 370835 4120 4188 2006-09-16 03:36:03.858 AFD: audio timecode 376926 376926 4188 4212 2006-09-16 03:36:03.859 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=4188 2006-09-16 03:36:03.860 AFD: audio timecode 379086 379086 4212 4236 2006-09-16 03:36:03.864 NVP: A/V Divergence: -1.47035, Rate: 0.140125, Warpfactor: 0.987715, warpfactor_avg: 0.999954 2006-09-16 03:36:03.860 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=4212 2006-09-16 03:36:03.869 AO: Broadcasting free space avail 2006-09-16 03:36:03.870 AO: Broadcasting free space avail 2006-09-16 03:36:03.870 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:03.870 AO: Broadcasting free space avail 2006-09-16 03:36:03.874 NVP: prebuffering pause 2006-09-16 03:36:03.874 NVP: Waiting for prebuffer.. 0 ALdAAALAAAAAAAAA 2006-09-16 03:36:03.881 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:03.881 AO: 50784 bytes free on soundcard 2006-09-16 03:36:03.894 AO: 53216 bytes free on soundcard 2006-09-16 03:36:03.901 AFD: video timecode 374435 374435 4160 4236 2006-09-16 03:36:03.906 AFD: audio timecode 381246 381246 4236 4260 2006-09-16 03:36:03.907 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4236 2006-09-16 03:36:03.909 AO: 56032 bytes free on soundcard 2006-09-16 03:36:03.925 AO: 59104 bytes free on soundcard 2006-09-16 03:36:03.933 AO: 60704 bytes free on soundcard 2006-09-16 03:36:03.941 AFD: video timecode 378035 378035 4200 4260 2006-09-16 03:36:03.943 AFD: audio timecode 383406 383406 4260 4284 2006-09-16 03:36:03.943 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=4260 2006-09-16 03:36:03.946 AO: 63168 bytes free on soundcard 2006-09-16 03:36:03.949 AFD: audio timecode 385566 385566 4284 4308 2006-09-16 03:36:03.949 AO: _AddSamples bytes=4608, used=10753, free=757247, timecode=4284 2006-09-16 03:36:03.955 NVP: A/V Divergence: -1.2465, Rate: 0.22385, Warpfactor: 0.988209, warpfactor_avg: 0.999953 2006-09-16 03:36:03.956 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:03.960 AO: Broadcasting free space avail 2006-09-16 03:36:03.961 AO: Broadcasting free space avail 2006-09-16 03:36:03.961 AO: Broadcasting free space avail 2006-09-16 03:36:03.961 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.961 AO: Broadcasting free space avail 2006-09-16 03:36:03.968 NVP: A/V Divergence: -1.26612, Rate: -0.019625, Warpfactor: 0.988703, warpfactor_avg: 0.999953 2006-09-16 03:36:03.977 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.977 AO: Broadcasting free space avail 2006-09-16 03:36:03.989 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:03.990 AO: Broadcasting free space avail 2006-09-16 03:36:03.993 AFD: video timecode 392435 381635 4240 4308 2006-09-16 03:36:03.997 AFD: audio timecode 387726 387726 4308 4332 2006-09-16 03:36:03.997 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=4308 2006-09-16 03:36:03.999 AFD: audio timecode 389886 389886 4332 4356 2006-09-16 03:36:03.999 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=4332 2006-09-16 03:36:04.001 AO: Broadcasting free space avail 2006-09-16 03:36:04.001 AO: Broadcasting free space avail 2006-09-16 03:36:04.002 AO: Broadcasting free space avail 2006-09-16 03:36:04.002 AO: Broadcasting free space avail 2006-09-16 03:36:04.007 NVP: A/V Divergence: -1.27457, Rate: -0.00845, Warpfactor: 0.989197, warpfactor_avg: 0.999952 2006-09-16 03:36:04.013 AO: Broadcasting free space avail 2006-09-16 03:36:04.022 AO: Broadcasting free space avail 2006-09-16 03:36:04.029 AFD: video timecode 385235 385235 4280 4356 2006-09-16 03:36:04.031 AFD: audio timecode 392046 392046 4356 4380 2006-09-16 03:36:04.031 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=4356 2006-09-16 03:36:04.033 AO: Broadcasting free space avail 2006-09-16 03:36:04.034 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.034 AO: Broadcasting free space avail 2006-09-16 03:36:04.042 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.042 AO: Broadcasting free space avail 2006-09-16 03:36:04.054 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.054 AO: Broadcasting free space avail 2006-09-16 03:36:04.063 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.063 AO: Broadcasting free space avail 2006-09-16 03:36:04.069 AFD: video timecode 388835 388835 4320 4380 2006-09-16 03:36:04.073 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.074 AO: Broadcasting free space avail 2006-09-16 03:36:04.078 NVP: A/V Divergence: -1.40593, Rate: -0.13135, Warpfactor: 0.988702, warpfactor_avg: 0.999951 2006-09-16 03:36:04.081 AFD: audio timecode 394205 394205 4380 4404 2006-09-16 03:36:04.081 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=4380 2006-09-16 03:36:04.083 AFD: audio timecode 396365 396365 4404 4428 2006-09-16 03:36:04.083 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=4404 2006-09-16 03:36:04.086 AO: Broadcasting free space avail 2006-09-16 03:36:04.086 AO: Broadcasting free space avail 2006-09-16 03:36:04.092 NVP: A/V Divergence: -1.41692, Rate: -0.011, Warpfactor: 0.988528, warpfactor_avg: 0.99995 2006-09-16 03:36:04.087 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.094 AO: Broadcasting free space avail 2006-09-16 03:36:04.106 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.106 AO: Broadcasting free space avail 2006-09-16 03:36:04.114 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.115 AO: Broadcasting free space avail 2006-09-16 03:36:04.121 AFD: video timecode 403235 392435 4360 4428 2006-09-16 03:36:04.123 AFD: audio timecode 398525 398525 4428 4452 2006-09-16 03:36:04.123 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4428 2006-09-16 03:36:04.126 AO: Broadcasting free space avail 2006-09-16 03:36:04.126 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.126 AO: Broadcasting free space avail 2006-09-16 03:36:04.129 AFD: audio timecode 400685 400685 4452 4476 2006-09-16 03:36:04.130 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=4452 2006-09-16 03:36:04.136 NVP: A/V Divergence: -1.43767, Rate: -0.02075, Warpfactor: 0.988284, warpfactor_avg: 0.99995 2006-09-16 03:36:04.136 AO: Broadcasting free space avail 2006-09-16 03:36:04.137 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.137 AO: Broadcasting free space avail 2006-09-16 03:36:04.149 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.150 AO: Broadcasting free space avail 2006-09-16 03:36:04.157 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.157 AO: Broadcasting free space avail 2006-09-16 03:36:04.157 AFD: video timecode 396035 396035 4400 4476 2006-09-16 03:36:04.159 AFD: audio timecode 402845 402845 4476 4500 2006-09-16 03:36:04.159 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=4476 2006-09-16 03:36:04.166 AO: Broadcasting free space avail 2006-09-16 03:36:04.166 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.166 AO: Broadcasting free space avail 2006-09-16 03:36:04.170 NVP: A/V Divergence: -1.422, Rate: 0.015675, Warpfactor: 0.9887, warpfactor_avg: 0.999949 2006-09-16 03:36:04.181 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.181 AO: Broadcasting free space avail 2006-09-16 03:36:04.192 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.193 AO: Broadcasting free space avail 2006-09-16 03:36:04.200 AFD: video timecode 399635 399635 4440 4500 2006-09-16 03:36:04.202 AFD: audio timecode 405005 405005 4500 4524 2006-09-16 03:36:04.203 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=4500 2006-09-16 03:36:04.206 AFD: audio timecode 407165 407165 4524 4548 2006-09-16 03:36:04.206 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=4524 2006-09-16 03:36:04.208 AO: Broadcasting free space avail 2006-09-16 03:36:04.209 AO: Broadcasting free space avail 2006-09-16 03:36:04.209 AO: Broadcasting free space avail 2006-09-16 03:36:04.209 AO: Broadcasting free space avail 2006-09-16 03:36:04.217 NVP: A/V Divergence: -1.43525, Rate: -0.01325, Warpfactor: 0.988361, warpfactor_avg: 0.999948 2006-09-16 03:36:04.219 AO: Broadcasting free space avail 2006-09-16 03:36:04.229 AO: Broadcasting free space avail 2006-09-16 03:36:04.237 AO: Broadcasting free space avail 2006-09-16 03:36:04.241 AFD: video timecode 414035 403235 4480 4548 2006-09-16 03:36:04.243 AFD: audio timecode 409325 409325 4548 4572 2006-09-16 03:36:04.243 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=4548 2006-09-16 03:36:04.244 AFD: audio timecode 411485 411485 4572 4596 2006-09-16 03:36:04.245 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=4572 2006-09-16 03:36:04.252 AO: Broadcasting free space avail 2006-09-16 03:36:04.252 AO: Broadcasting free space avail 2006-09-16 03:36:04.252 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.253 AO: Broadcasting free space avail 2006-09-16 03:36:04.255 NVP: A/V Divergence: -1.36392, Rate: 0.071325, Warpfactor: 0.988856, warpfactor_avg: 0.999947 2006-09-16 03:36:04.271 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.271 AO: Broadcasting free space avail 2006-09-16 03:36:04.277 AFD: video timecode 406835 406835 4520 4596 2006-09-16 03:36:04.281 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.281 AO: Broadcasting free space avail 2006-09-16 03:36:04.280 AFD: audio timecode 413645 413645 4596 4620 2006-09-16 03:36:04.284 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=4596 2006-09-16 03:36:04.291 NVP: A/V Divergence: -1.37917, Rate: -0.01525, Warpfactor: 0.988792, warpfactor_avg: 0.999947 2006-09-16 03:36:04.292 AO: Broadcasting free space avail 2006-09-16 03:36:04.294 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.294 AO: Broadcasting free space avail 2006-09-16 03:36:04.305 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.305 AO: Broadcasting free space avail 2006-09-16 03:36:04.313 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.313 AO: Broadcasting free space avail 2006-09-16 03:36:04.317 AFD: video timecode 410435 410435 4560 4620 2006-09-16 03:36:04.319 AFD: audio timecode 415805 415805 4620 4644 2006-09-16 03:36:04.319 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4620 2006-09-16 03:36:04.320 AFD: audio timecode 417965 417965 4644 4668 2006-09-16 03:36:04.321 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=4644 2006-09-16 03:36:04.326 AO: Broadcasting free space avail 2006-09-16 03:36:04.326 AO: Broadcasting free space avail 2006-09-16 03:36:04.326 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.326 AO: Broadcasting free space avail 2006-09-16 03:36:04.332 NVP: A/V Divergence: -1.39062, Rate: -0.01145, Warpfactor: 0.988731, warpfactor_avg: 0.999946 2006-09-16 03:36:04.333 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.333 AO: Broadcasting free space avail 2006-09-16 03:36:04.346 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.347 AO: Broadcasting free space avail 2006-09-16 03:36:04.356 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.356 AO: Broadcasting free space avail 2006-09-16 03:36:04.362 AFD: video timecode 424835 414035 4600 4668 2006-09-16 03:36:04.370 AFD: audio timecode 420125 420125 4668 4692 2006-09-16 03:36:04.371 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=4668 2006-09-16 03:36:04.373 AFD: audio timecode 422285 422285 4692 4716 2006-09-16 03:36:04.373 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=4692 2006-09-16 03:36:04.379 AO: Broadcasting free space avail 2006-09-16 03:36:04.379 AO: Broadcasting free space avail 2006-09-16 03:36:04.380 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:04.380 AO: Broadcasting free space avail 2006-09-16 03:36:04.384 NVP: A/V Divergence: -1.4742, Rate: -0.083575, Warpfactor: 0.988236, warpfactor_avg: 0.999945 2006-09-16 03:36:04.386 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:04.386 AO: Broadcasting free space avail 2006-09-16 03:36:04.387 NVP: prebuffering pause 2006-09-16 03:36:04.387 NVP: Waiting for prebuffer.. 0 LAAAAAAAAAAAALdA 2006-09-16 03:36:04.397 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:04.398 AO: 55680 bytes free on soundcard 2006-09-16 03:36:04.405 AO: 57184 bytes free on soundcard 2006-09-16 03:36:04.409 AFD: video timecode 417635 417635 4640 4716 2006-09-16 03:36:04.415 AFD: audio timecode 424445 424445 4716 4740 2006-09-16 03:36:04.415 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=4716 2006-09-16 03:36:04.421 AO: 60192 bytes free on soundcard 2006-09-16 03:36:04.434 AO: 62688 bytes free on soundcard 2006-09-16 03:36:04.441 AFD: video timecode 421235 421235 4680 4740 2006-09-16 03:36:04.444 AFD: audio timecode 426605 426605 4740 4764 2006-09-16 03:36:04.445 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=4740 2006-09-16 03:36:04.449 AO: 61472 bytes free on soundcard 2006-09-16 03:36:04.449 AFD: audio timecode 428765 428765 4764 4788 2006-09-16 03:36:04.456 AO: _AddSamples bytes=4608, used=12801, free=755199, timecode=4764 2006-09-16 03:36:04.459 NVP: A/V Divergence: -1.3244, Rate: 0.1498, Warpfactor: 0.98873, warpfactor_avg: 0.999944 2006-09-16 03:36:04.463 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:04.464 AO: Broadcasting free space avail 2006-09-16 03:36:04.464 AO: Broadcasting free space avail 2006-09-16 03:36:04.464 AO: Broadcasting free space avail 2006-09-16 03:36:04.464 AO: Broadcasting free space avail 2006-09-16 03:36:04.465 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.465 AO: Broadcasting free space avail 2006-09-16 03:36:04.473 NVP: A/V Divergence: -1.21205, Rate: 0.11235, Warpfactor: 0.989225, warpfactor_avg: 0.999944 2006-09-16 03:36:04.474 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.474 AO: Broadcasting free space avail 2006-09-16 03:36:04.488 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.489 AO: Broadcasting free space avail 2006-09-16 03:36:04.502 AFD: video timecode 435635 424835 4720 4788 2006-09-16 03:36:04.503 AFD: audio timecode 430925 430925 4788 4812 2006-09-16 03:36:04.504 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=4788 2006-09-16 03:36:04.505 AO: Broadcasting free space avail 2006-09-16 03:36:04.505 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:04.505 AO: Broadcasting free space avail 2006-09-16 03:36:04.510 AFD: audio timecode 433085 433085 4812 4836 2006-09-16 03:36:04.510 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4812 2006-09-16 03:36:04.519 NVP: A/V Divergence: -1.15278, Rate: 0.059275, Warpfactor: 0.989719, warpfactor_avg: 0.999943 2006-09-16 03:36:04.519 AO: Broadcasting free space avail 2006-09-16 03:36:04.520 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.520 AO: Broadcasting free space avail 2006-09-16 03:36:04.529 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.532 AO: Broadcasting free space avail 2006-09-16 03:36:04.540 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.541 AO: Broadcasting free space avail 2006-09-16 03:36:04.546 AFD: video timecode 428435 428435 4760 4836 2006-09-16 03:36:04.547 AFD: audio timecode 435245 435245 4836 4860 2006-09-16 03:36:04.550 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=4836 2006-09-16 03:36:04.555 AO: Broadcasting free space avail 2006-09-16 03:36:04.555 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.555 AO: Broadcasting free space avail 2006-09-16 03:36:04.560 NVP: A/V Divergence: -1.12083, Rate: 0.03195, Warpfactor: 0.990214, warpfactor_avg: 0.999942 2006-09-16 03:36:04.562 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:04.562 AO: Broadcasting free space avail 2006-09-16 03:36:04.562 NVP: prebuffering pause 2006-09-16 03:36:04.563 NVP: Waiting for prebuffer.. 0 dLDLAAAAAAAAAAAA 2006-09-16 03:36:04.575 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:04.576 AO: 53024 bytes free on soundcard 2006-09-16 03:36:04.581 AO: 54144 bytes free on soundcard 2006-09-16 03:36:04.589 AFD: video timecode 432035 432035 4800 4860 2006-09-16 03:36:04.591 AFD: audio timecode 437405 437405 4860 4884 2006-09-16 03:36:04.591 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=4860 2006-09-16 03:36:04.594 AFD: audio timecode 439565 439565 4884 4908 2006-09-16 03:36:04.594 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=4884 2006-09-16 03:36:04.597 AO: 57088 bytes free on soundcard 2006-09-16 03:36:04.611 AO: 59776 bytes free on soundcard 2006-09-16 03:36:04.617 AFD: video timecode 446435 435635 4840 4908 2006-09-16 03:36:04.619 AFD: audio timecode 441725 441725 4908 4932 2006-09-16 03:36:04.619 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=4908 2006-09-16 03:36:04.620 AFD: audio timecode 443885 443885 4932 4956 2006-09-16 03:36:04.621 AO: _AddSamples bytes=4608, used=16385, free=751615, timecode=4932 2006-09-16 03:36:04.623 AO: 62048 bytes free on soundcard 2006-09-16 03:36:04.627 NVP: A/V Divergence: -0.8906, Rate: 0.230225, Warpfactor: 0.990709, warpfactor_avg: 0.999942 2006-09-16 03:36:04.637 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:04.637 AO: Broadcasting free space avail 2006-09-16 03:36:04.638 AO: Broadcasting free space avail 2006-09-16 03:36:04.638 AO: Broadcasting free space avail 2006-09-16 03:36:04.638 AO: Broadcasting free space avail 2006-09-16 03:36:04.639 AO: Broadcasting free space avail 2006-09-16 03:36:04.639 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.639 AO: Broadcasting free space avail 2006-09-16 03:36:04.645 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.645 AO: Broadcasting free space avail 2006-09-16 03:36:04.650 AFD: video timecode 439235 439235 4880 4956 2006-09-16 03:36:04.658 AFD: audio timecode 446045 446045 4956 4980 2006-09-16 03:36:04.659 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=4956 2006-09-16 03:36:04.661 NVP: A/V Divergence: -0.8492, Rate: 0.0414, Warpfactor: 0.991204, warpfactor_avg: 0.999941 2006-09-16 03:36:04.661 AO: Broadcasting free space avail 2006-09-16 03:36:04.665 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.666 AO: Broadcasting free space avail 2006-09-16 03:36:04.673 NVP: A/V Divergence: -0.7244, Rate: 0.1248, Warpfactor: 0.9917, warpfactor_avg: 0.999941 2006-09-16 03:36:04.683 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.683 AO: Broadcasting free space avail 2006-09-16 03:36:04.698 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.699 AO: Broadcasting free space avail 2006-09-16 03:36:04.705 AFD: video timecode 442835 442835 4920 4980 2006-09-16 03:36:04.707 AFD: audio timecode 448205 448205 4980 5004 2006-09-16 03:36:04.707 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:04.707 AO: Broadcasting free space avail 2006-09-16 03:36:04.708 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=4980 2006-09-16 03:36:04.710 AFD: audio timecode 450365 450365 5004 5028 2006-09-16 03:36:04.711 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=5004 2006-09-16 03:36:04.714 AO: Broadcasting free space avail 2006-09-16 03:36:04.717 AO: Broadcasting free space avail 2006-09-16 03:36:04.717 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.718 AO: Broadcasting free space avail 'video_output' mean = '46769.25', std. dev. = '23883.35', fps = '21.38' 2006-09-16 03:36:04.719 A/V avsync_delay: -14, avsync_avg: -28, warpfactor: 0.9917, warpfactor_avg: 0.999941 2006-09-16 03:36:04.726 NVP: A/V Divergence: -0.6933, Rate: 0.0311, Warpfactor: 0.992196, warpfactor_avg: 0.99994 2006-09-16 03:36:04.727 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.727 AO: Broadcasting free space avail 2006-09-16 03:36:04.741 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.741 AO: Broadcasting free space avail 2006-09-16 03:36:04.749 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.750 AO: Broadcasting free space avail 2006-09-16 03:36:04.753 AFD: video timecode 457234 446435 4960 5028 2006-09-16 03:36:04.756 AFD: audio timecode 452525 452525 5028 5052 2006-09-16 03:36:04.759 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5028 2006-09-16 03:36:04.761 AFD: audio timecode 454685 454685 5052 5076 2006-09-16 03:36:04.761 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=5052 2006-09-16 03:36:04.764 AO: Broadcasting free space avail 2006-09-16 03:36:04.764 AO: Broadcasting free space avail 2006-09-16 03:36:04.765 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.765 AO: Broadcasting free space avail 2006-09-16 03:36:04.772 NVP: A/V Divergence: -0.713725, Rate: -0.020425, Warpfactor: 0.992692, warpfactor_avg: 0.99994 2006-09-16 03:36:04.781 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.790 AO: Broadcasting free space avail 2006-09-16 03:36:04.794 AFD: video timecode 450035 450035 5000 5076 2006-09-16 03:36:04.796 AFD: audio timecode 456845 456845 5076 5100 2006-09-16 03:36:04.796 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=5076 2006-09-16 03:36:04.799 AO: Broadcasting free space avail 2006-09-16 03:36:04.799 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:04.799 AO: Broadcasting free space avail 2006-09-16 03:36:04.806 NVP: A/V Divergence: -0.685275, Rate: 0.02845, Warpfactor: 0.993188, warpfactor_avg: 0.999939 2006-09-16 03:36:04.808 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:04.808 AO: Broadcasting free space avail 2006-09-16 03:36:04.821 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:04.821 AO: Broadcasting free space avail 2006-09-16 03:36:04.829 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:04.829 AO: Broadcasting free space avail 2006-09-16 03:36:04.833 AFD: video timecode 453635 453635 5040 5100 2006-09-16 03:36:04.838 AFD: audio timecode 459005 459005 5100 5124 2006-09-16 03:36:04.839 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=5100 2006-09-16 03:36:04.844 AO: Broadcasting free space avail 2006-09-16 03:36:04.844 AO: Broadcasting free space avail 2006-09-16 03:36:04.845 AO: Broadcasting free space avail 2006-09-16 03:36:04.840 AFD: audio timecode 461165 461165 5124 5148 2006-09-16 03:36:04.846 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=5124 2006-09-16 03:36:04.858 NVP: A/V Divergence: -0.73895, Rate: -0.053675, Warpfactor: 0.993598, warpfactor_avg: 0.999939 2006-09-16 03:36:04.858 AO: Broadcasting free space avail 2006-09-16 03:36:04.859 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:04.859 AO: Broadcasting free space avail 2006-09-16 03:36:04.863 NVP: prebuffering pause 2006-09-16 03:36:04.864 NVP: Waiting for prebuffer.. 0 AAAADAALADLAAAAA 2006-09-16 03:36:04.873 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:04.873 AO: 48768 bytes free on soundcard 2006-09-16 03:36:04.883 AO: 50656 bytes free on soundcard 2006-09-16 03:36:04.890 AFD: video timecode 468034 457234 5080 5148 2006-09-16 03:36:04.891 AFD: audio timecode 463325 463325 5148 5172 2006-09-16 03:36:04.892 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5148 2006-09-16 03:36:04.896 AFD: audio timecode 465484 465484 5172 5196 2006-09-16 03:36:04.896 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=5172 2006-09-16 03:36:04.897 AO: 53248 bytes free on soundcard 2006-09-16 03:36:04.913 AO: 56352 bytes free on soundcard 2006-09-16 03:36:04.924 AO: 58528 bytes free on soundcard 2006-09-16 03:36:04.929 AFD: video timecode 460834 460834 5120 5196 2006-09-16 03:36:04.930 AFD: audio timecode 467644 467644 5196 5220 2006-09-16 03:36:04.931 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=5196 2006-09-16 03:36:04.933 NVP: A/V Divergence: -0.6042, Rate: 0.13475, Warpfactor: 0.994095, warpfactor_avg: 0.999938 2006-09-16 03:36:04.933 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:04.934 AO: Broadcasting free space avail 2006-09-16 03:36:04.934 AO: Broadcasting free space avail 2006-09-16 03:36:04.934 AO: Broadcasting free space avail 2006-09-16 03:36:04.935 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.935 AO: Broadcasting free space avail 2006-09-16 03:36:04.949 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.949 AO: Broadcasting free space avail 2006-09-16 03:36:04.959 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:04.959 AO: Broadcasting free space avail 2006-09-16 03:36:04.963 AFD: video timecode 464434 464434 5160 5220 2006-09-16 03:36:04.965 AFD: audio timecode 469804 469804 5220 5244 2006-09-16 03:36:04.966 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5220 2006-09-16 03:36:04.969 AFD: audio timecode 471964 471964 5244 5268 2006-09-16 03:36:04.970 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=5244 2006-09-16 03:36:04.973 AO: Broadcasting free space avail 2006-09-16 03:36:04.973 AO: Broadcasting free space avail 2006-09-16 03:36:04.974 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.974 AO: Broadcasting free space avail 2006-09-16 03:36:04.993 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:04.993 AO: Broadcasting free space avail 2006-09-16 03:36:05.001 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:05.001 AO: Broadcasting free space avail 2006-09-16 03:36:05.006 AFD: video timecode 478834 468034 5200 5268 2006-09-16 03:36:05.008 AFD: audio timecode 474124 474124 5268 5292 2006-09-16 03:36:05.010 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=5268 2006-09-16 03:36:05.012 AFD: audio timecode 476284 476284 5292 5316 2006-09-16 03:36:05.012 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=5292 2006-09-16 03:36:05.016 NVP: A/V Divergence: -1.09065, Rate: -0.48645, Warpfactor: 0.993598, warpfactor_avg: 0.999938 2006-09-16 03:36:05.019 AO: Broadcasting free space avail 2006-09-16 03:36:05.019 AO: Broadcasting free space avail 2006-09-16 03:36:05.020 AO: Broadcasting free space avail 2006-09-16 03:36:05.020 AO: Broadcasting free space avail 2006-09-16 03:36:05.029 NVP: A/V Divergence: -1.36172, Rate: -0.271075, Warpfactor: 0.993101, warpfactor_avg: 0.999937 2006-09-16 03:36:05.031 AO: Broadcasting free space avail 2006-09-16 03:36:05.041 AO: Broadcasting free space avail 2006-09-16 03:36:05.049 AO: Broadcasting free space avail 2006-09-16 03:36:05.053 AFD: video timecode 471634 471634 5240 5316 2006-09-16 03:36:05.056 AFD: audio timecode 478444 478444 5316 5340 2006-09-16 03:36:05.057 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=5316 2006-09-16 03:36:05.069 AO: Broadcasting free space avail 2006-09-16 03:36:05.070 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.070 AO: Broadcasting free space avail 2006-09-16 03:36:05.060 NVP: A/V Divergence: -1.45878, Rate: -0.09705, Warpfactor: 0.992605, warpfactor_avg: 0.999937 2006-09-16 03:36:05.077 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.078 AO: Broadcasting free space avail 2006-09-16 03:36:05.091 AFD: video timecode 475234 475234 5280 5340 2006-09-16 03:36:05.093 AFD: audio timecode 480604 480604 5340 5364 2006-09-16 03:36:05.093 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5340 2006-09-16 03:36:05.095 AFD: audio timecode 482764 482764 5364 5388 2006-09-16 03:36:05.095 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=5364 2006-09-16 03:36:05.097 AO: Broadcasting free space avail 2006-09-16 03:36:05.097 AO: Broadcasting free space avail 2006-09-16 03:36:05.097 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.098 AO: Broadcasting free space avail 2006-09-16 03:36:05.109 NVP: A/V Divergence: -1.62532, Rate: -0.16655, Warpfactor: 0.992108, warpfactor_avg: 0.999936 2006-09-16 03:36:05.110 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.110 AO: Broadcasting free space avail 2006-09-16 03:36:05.125 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.125 AO: Broadcasting free space avail 2006-09-16 03:36:05.133 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.133 AO: Broadcasting free space avail 2006-09-16 03:36:05.140 AFD: video timecode 489634 478834 5320 5388 2006-09-16 03:36:05.147 AFD: audio timecode 484924 484924 5388 5412 2006-09-16 03:36:05.148 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=5388 2006-09-16 03:36:05.149 AFD: audio timecode 487084 487084 5412 5436 2006-09-16 03:36:05.150 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=5412 2006-09-16 03:36:05.151 AO: Broadcasting free space avail 2006-09-16 03:36:05.152 AO: Broadcasting free space avail 2006-09-16 03:36:05.152 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.152 AO: Broadcasting free space avail 2006-09-16 03:36:05.153 NVP: A/V Divergence: -1.68148, Rate: -0.05615, Warpfactor: 0.991612, warpfactor_avg: 0.999936 2006-09-16 03:36:05.159 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.159 AO: Broadcasting free space avail 2006-09-16 03:36:05.163 NVP: A/V Divergence: -1.6611, Rate: 0.020375, Warpfactor: 0.991116, warpfactor_avg: 0.999935 2006-09-16 03:36:05.177 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.178 AO: Broadcasting free space avail 2006-09-16 03:36:05.186 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.187 AO: Broadcasting free space avail 2006-09-16 03:36:05.192 AFD: video timecode 482434 482434 5360 5436 2006-09-16 03:36:05.196 NVP: A/V Divergence: -1.52708, Rate: 0.134025, Warpfactor: 0.990621, warpfactor_avg: 0.999935 2006-09-16 03:36:05.198 AFD: audio timecode 489244 489244 5436 5460 2006-09-16 03:36:05.199 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=5436 2006-09-16 03:36:05.201 AO: Broadcasting free space avail 2006-09-16 03:36:05.201 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:05.201 AO: Broadcasting free space avail 2006-09-16 03:36:05.202 NVP: prebuffering pause 2006-09-16 03:36:05.203 NVP: Waiting for prebuffer.. 0 LALDAAAAAAAAAdAA 2006-09-16 03:36:05.217 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:05.217 AO: 57472 bytes free on soundcard 2006-09-16 03:36:05.227 AO: 59360 bytes free on soundcard 2006-09-16 03:36:05.228 AFD: video timecode 486034 486034 5400 5460 2006-09-16 03:36:05.231 AFD: audio timecode 491404 491404 5460 5484 2006-09-16 03:36:05.231 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=5460 2006-09-16 03:36:05.233 AFD: audio timecode 493564 493564 5484 5508 2006-09-16 03:36:05.235 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=5484 2006-09-16 03:36:05.242 AO: 62208 bytes free on soundcard 2006-09-16 03:36:05.253 AO: 60256 bytes free on soundcard 2006-09-16 03:36:05.263 AO: 62112 bytes free on soundcard 2006-09-16 03:36:05.263 AFD: video timecode 500434 489634 5440 5508 2006-09-16 03:36:05.265 AFD: audio timecode 495724 495724 5508 5532 2006-09-16 03:36:05.265 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=5508 2006-09-16 03:36:05.268 AFD: audio timecode 497884 497884 5532 5556 2006-09-16 03:36:05.272 NVP: A/V Divergence: -1.3078, Rate: 0.219275, Warpfactor: 0.991116, warpfactor_avg: 0.999934 2006-09-16 03:36:05.273 AO: _AddSamples bytes=4608, used=16897, free=751103, timecode=5532 2006-09-16 03:36:05.273 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:05.275 AO: Broadcasting free space avail 2006-09-16 03:36:05.275 AO: Broadcasting free space avail 2006-09-16 03:36:05.276 AO: Broadcasting free space avail 2006-09-16 03:36:05.276 AO: Broadcasting free space avail 2006-09-16 03:36:05.276 AO: Broadcasting free space avail 2006-09-16 03:36:05.276 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.277 AO: Broadcasting free space avail 2006-09-16 03:36:05.283 NVP: A/V Divergence: -1.1121, Rate: 0.1957, Warpfactor: 0.991612, warpfactor_avg: 0.999933 2006-09-16 03:36:05.289 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.289 AO: Broadcasting free space avail 2006-09-16 03:36:05.298 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.298 AO: Broadcasting free space avail 2006-09-16 03:36:05.301 AFD: video timecode 493234 493234 5480 5556 2006-09-16 03:36:05.303 AFD: audio timecode 500044 500044 5556 5580 2006-09-16 03:36:05.303 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=5556 2006-09-16 03:36:05.311 AO: Broadcasting free space avail 2006-09-16 03:36:05.311 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.311 AO: Broadcasting free space avail 2006-09-16 03:36:05.317 NVP: A/V Divergence: -0.934075, Rate: 0.178025, Warpfactor: 0.992107, warpfactor_avg: 0.999933 2006-09-16 03:36:05.325 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.326 AO: Broadcasting free space avail 2006-09-16 03:36:05.339 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:05.340 AO: Broadcasting free space avail 2006-09-16 03:36:05.346 AFD: video timecode 496834 496834 5520 5580 2006-09-16 03:36:05.348 AFD: audio timecode 502204 502204 5580 5604 2006-09-16 03:36:05.348 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=5580 2006-09-16 03:36:05.351 AFD: audio timecode 504364 504364 5604 5628 2006-09-16 03:36:05.352 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=5604 2006-09-16 03:36:05.352 AFD: positionMap[ 9 ] == 4259878. 2006-09-16 03:36:05.353 AO: Broadcasting free space avail 2006-09-16 03:36:05.354 AO: Broadcasting free space avail 2006-09-16 03:36:05.354 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.354 AO: Broadcasting free space avail 2006-09-16 03:36:05.370 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.370 AO: Broadcasting free space avail 2006-09-16 03:36:05.378 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:05.379 AO: Broadcasting free space avail 2006-09-16 03:36:05.381 AFD: video timecode 511234 500434 5560 5628 2006-09-16 03:36:05.382 AFD: audio timecode 506524 506524 5628 5652 2006-09-16 03:36:05.383 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=5628 2006-09-16 03:36:05.384 AFD: audio timecode 508684 508684 5652 5676 2006-09-16 03:36:05.385 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=5652 2006-09-16 03:36:05.390 AO: Broadcasting free space avail 2006-09-16 03:36:05.391 AO: Broadcasting free space avail 2006-09-16 03:36:05.391 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.391 AO: Broadcasting free space avail 2006-09-16 03:36:05.395 NVP: A/V Divergence: -1.03805, Rate: -0.103975, Warpfactor: 0.991611, warpfactor_avg: 0.999932 2006-09-16 03:36:05.397 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.398 AO: Broadcasting free space avail 2006-09-16 03:36:05.408 NVP: A/V Divergence: -0.934775, Rate: 0.103275, Warpfactor: 0.992107, warpfactor_avg: 0.999932 2006-09-16 03:36:05.409 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.410 AO: Broadcasting free space avail 2006-09-16 03:36:05.421 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.421 AO: Broadcasting free space avail 2006-09-16 03:36:05.429 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.429 AO: Broadcasting free space avail 2006-09-16 03:36:05.430 AFD: video timecode 504034 504034 5600 5676 2006-09-16 03:36:05.433 AFD: audio timecode 510844 510844 5676 5700 2006-09-16 03:36:05.433 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=5676 2006-09-16 03:36:05.440 NVP: A/V Divergence: -0.807325, Rate: 0.12745, Warpfactor: 0.992603, warpfactor_avg: 0.999931 2006-09-16 03:36:05.440 AO: Broadcasting free space avail 2006-09-16 03:36:05.442 AO: Broadcasting free space avail 2006-09-16 03:36:05.443 AO: Broadcasting free space avail 2006-09-16 03:36:05.450 AO: Broadcasting free space avail 2006-09-16 03:36:05.462 AO: Broadcasting free space avail 2006-09-16 03:36:05.469 AO: Broadcasting free space avail 2006-09-16 03:36:05.481 AFD: video timecode 507634 507634 5640 5700 2006-09-16 03:36:05.483 AFD: audio timecode 513004 513004 5700 5724 2006-09-16 03:36:05.483 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=5700 2006-09-16 03:36:05.485 AO: Broadcasting free space avail 2006-09-16 03:36:05.486 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.486 AO: Broadcasting free space avail 2006-09-16 03:36:05.485 AFD: audio timecode 515164 515164 5724 5748 2006-09-16 03:36:05.499 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5724 2006-09-16 03:36:05.501 AO: Broadcasting free space avail 2006-09-16 03:36:05.502 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.502 AO: Broadcasting free space avail 2006-09-16 03:36:05.506 NVP: A/V Divergence: -0.886725, Rate: -0.0794, Warpfactor: 0.992203, warpfactor_avg: 0.999931 2006-09-16 03:36:05.517 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.517 AO: Broadcasting free space avail 2006-09-16 03:36:05.525 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.525 AO: Broadcasting free space avail 2006-09-16 03:36:05.532 AFD: video timecode 522034 511234 5680 5748 2006-09-16 03:36:05.539 AFD: audio timecode 517324 517324 5748 5772 2006-09-16 03:36:05.540 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=5748 2006-09-16 03:36:05.541 AFD: audio timecode 519484 519484 5772 5796 2006-09-16 03:36:05.542 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=5772 2006-09-16 03:36:05.549 AO: Broadcasting free space avail 2006-09-16 03:36:05.549 AO: Broadcasting free space avail 2006-09-16 03:36:05.550 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:05.550 AO: Broadcasting free space avail 2006-09-16 03:36:05.536 NVP: A/V Divergence: -0.833775, Rate: 0.05295, Warpfactor: 0.992699, warpfactor_avg: 0.99993 2006-09-16 03:36:05.554 NVP: prebuffering pause 2006-09-16 03:36:05.555 NVP: Waiting for prebuffer.. 0 AAAAAAALdAALAAAA 2006-09-16 03:36:05.558 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:05.559 AO: 49312 bytes free on soundcard 2006-09-16 03:36:05.568 AO: 51008 bytes free on soundcard 2006-09-16 03:36:05.573 AO: 52032 bytes free on soundcard 2006-09-16 03:36:05.577 AFD: video timecode 514834 514834 5720 5796 2006-09-16 03:36:05.579 AFD: audio timecode 521644 521644 5796 5820 2006-09-16 03:36:05.579 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5796 2006-09-16 03:36:05.586 AO: 54624 bytes free on soundcard 2006-09-16 03:36:05.603 AO: 57792 bytes free on soundcard 2006-09-16 03:36:05.609 AFD: video timecode 518434 518434 5760 5820 2006-09-16 03:36:05.612 AFD: audio timecode 523804 523804 5820 5844 2006-09-16 03:36:05.612 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=5820 2006-09-16 03:36:05.613 AO: 59680 bytes free on soundcard 2006-09-16 03:36:05.619 NVP: A/V Divergence: -0.725325, Rate: 0.10845, Warpfactor: 0.993195, warpfactor_avg: 0.99993 2006-09-16 03:36:05.620 AFD: audio timecode 525964 525964 5844 5868 2006-09-16 03:36:05.621 AO: _AddSamples bytes=4608, used=11265, free=756735, timecode=5844 2006-09-16 03:36:05.626 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:05.627 AO: Broadcasting free space avail 2006-09-16 03:36:05.627 AO: Broadcasting free space avail 2006-09-16 03:36:05.628 AO: Broadcasting free space avail 2006-09-16 03:36:05.628 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.628 AO: Broadcasting free space avail 2006-09-16 03:36:05.632 NVP: A/V Divergence: -0.856475, Rate: -0.13115, Warpfactor: 0.992699, warpfactor_avg: 0.999929 2006-09-16 03:36:05.641 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.641 AO: Broadcasting free space avail 2006-09-16 03:36:05.650 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.650 AO: Broadcasting free space avail 2006-09-16 03:36:05.653 AFD: video timecode 532834 522034 5800 5868 2006-09-16 03:36:05.655 AFD: audio timecode 528124 528124 5868 5892 2006-09-16 03:36:05.655 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=5868 2006-09-16 03:36:05.660 AFD: audio timecode 530283 530283 5892 5916 2006-09-16 03:36:05.660 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=5892 2006-09-16 03:36:05.662 AO: Broadcasting free space avail 2006-09-16 03:36:05.663 AO: Broadcasting free space avail 2006-09-16 03:36:05.663 AO: Broadcasting free space avail 2006-09-16 03:36:05.663 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.663 AO: Broadcasting free space avail 2006-09-16 03:36:05.668 NVP: A/V Divergence: -0.9361, Rate: -0.079625, Warpfactor: 0.992202, warpfactor_avg: 0.999929 2006-09-16 03:36:05.675 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.675 AO: Broadcasting free space avail 2006-09-16 03:36:05.687 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.687 AO: Broadcasting free space avail 2006-09-16 03:36:05.693 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.694 AO: Broadcasting free space avail 2006-09-16 03:36:05.694 AFD: video timecode 525634 525634 5840 5916 2006-09-16 03:36:05.696 AFD: audio timecode 532443 532443 5916 5940 2006-09-16 03:36:05.696 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5916 2006-09-16 03:36:05.702 NVP: A/V Divergence: -0.958325, Rate: -0.022225, Warpfactor: 0.992085, warpfactor_avg: 0.999928 2006-09-16 03:36:05.703 AO: Broadcasting free space avail 2006-09-16 03:36:05.703 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.703 AO: Broadcasting free space avail 2006-09-16 03:36:05.721 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.721 AO: Broadcasting free space avail 2006-09-16 03:36:05.729 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:05.729 AO: Broadcasting free space avail 2006-09-16 03:36:05.730 AFD: video timecode 529234 529234 5880 5940 2006-09-16 03:36:05.732 AFD: audio timecode 534603 534603 5940 5964 2006-09-16 03:36:05.733 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=5940 2006-09-16 03:36:05.736 AFD: audio timecode 536763 536763 5964 5988 2006-09-16 03:36:05.736 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=5964 2006-09-16 03:36:05.739 AO: Broadcasting free space avail 2006-09-16 03:36:05.744 AO: Broadcasting free space avail 2006-09-16 03:36:05.744 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:05.744 AO: Broadcasting free space avail 2006-09-16 03:36:05.749 NVP: A/V Divergence: -1.01873, Rate: -0.0604, Warpfactor: 0.991589, warpfactor_avg: 0.999928 2006-09-16 03:36:05.757 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:05.757 AO: Broadcasting free space avail 2006-09-16 03:36:05.769 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:05.769 AO: Broadcasting free space avail 2006-09-16 03:36:05.773 AFD: video timecode 543634 532834 5920 5988 2006-09-16 03:36:05.775 AFD: audio timecode 538923 538923 5988 6012 2006-09-16 03:36:05.775 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5988 2006-09-16 03:36:05.779 AFD: audio timecode 541083 541083 6012 6036 2006-09-16 03:36:05.779 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=6012 2006-09-16 03:36:05.784 AO: Broadcasting free space avail 2006-09-16 03:36:05.784 AO: Broadcasting free space avail 2006-09-16 03:36:05.784 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:05.785 AO: Broadcasting free space avail 2006-09-16 03:36:05.790 NVP: A/V Divergence: -1.06403, Rate: -0.0453, Warpfactor: 0.991093, warpfactor_avg: 0.999927 2006-09-16 03:36:05.799 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:05.799 AO: Broadcasting free space avail 2006-09-16 03:36:05.810 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:05.810 AO: Broadcasting free space avail 2006-09-16 03:36:05.817 AFD: video timecode 536434 536434 5960 6036 2006-09-16 03:36:05.821 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:05.821 AO: Broadcasting free space avail 2006-09-16 03:36:05.821 AFD: audio timecode 543243 543243 6036 6060 2006-09-16 03:36:05.822 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=6036 2006-09-16 03:36:05.832 NVP: A/V Divergence: -1.0855, Rate: -0.021475, Warpfactor: 0.991072, warpfactor_avg: 0.999927 2006-09-16 03:36:05.833 AO: Broadcasting free space avail 2006-09-16 03:36:05.834 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.834 AO: Broadcasting free space avail 2006-09-16 03:36:05.849 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.849 AO: Broadcasting free space avail 2006-09-16 03:36:05.857 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:05.858 AO: Broadcasting free space avail 2006-09-16 03:36:05.861 AFD: video timecode 540034 540034 6000 6060 2006-09-16 03:36:05.869 AFD: audio timecode 545403 545403 6060 6084 2006-09-16 03:36:05.870 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=6060 2006-09-16 03:36:05.871 AFD: audio timecode 547563 547563 6084 6108 2006-09-16 03:36:05.872 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=6084 2006-09-16 03:36:05.872 AFD: positionMap[ 10 ] == 4644902. 2006-09-16 03:36:05.876 AO: Broadcasting free space avail 2006-09-16 03:36:05.877 AO: Broadcasting free space avail 2006-09-16 03:36:05.877 AO: Broadcasting free space avail 2006-09-16 03:36:05.877 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.877 AO: Broadcasting free space avail 2006-09-16 03:36:05.887 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.887 AO: Broadcasting free space avail 2006-09-16 03:36:05.891 NVP: A/V Divergence: -1.24538, Rate: -0.159875, Warpfactor: 0.990576, warpfactor_avg: 0.999926 2006-09-16 03:36:05.905 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:05.905 AO: Broadcasting free space avail 2006-09-16 03:36:05.910 NVP: prebuffering pause 2006-09-16 03:36:05.911 NVP: Waiting for prebuffer.. 0 AAALAALAAAAAADDA 2006-09-16 03:36:05.917 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:05.918 AO: 56800 bytes free on soundcard 2006-09-16 03:36:05.926 AO: 58496 bytes free on soundcard 2006-09-16 03:36:05.929 AFD: video timecode 554434 543634 6040 6108 2006-09-16 03:36:05.934 AFD: audio timecode 549723 549723 6108 6132 2006-09-16 03:36:05.934 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=6108 2006-09-16 03:36:05.936 AFD: audio timecode 551883 551883 6132 6156 2006-09-16 03:36:05.936 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=6132 2006-09-16 03:36:05.938 AO: 60640 bytes free on soundcard 2006-09-16 03:36:05.953 AO: 63552 bytes free on soundcard 2006-09-16 03:36:05.961 AO: 61024 bytes free on soundcard 2006-09-16 03:36:05.962 AFD: video timecode 547234 547234 6080 6156 2006-09-16 03:36:05.963 AFD: audio timecode 554043 554043 6156 6180 2006-09-16 03:36:05.964 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=6156 2006-09-16 03:36:05.972 NVP: A/V Divergence: -1.25902, Rate: -0.01365, Warpfactor: 0.990081, warpfactor_avg: 0.999925 2006-09-16 03:36:05.973 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:05.974 AO: Broadcasting free space avail 2006-09-16 03:36:05.974 AO: Broadcasting free space avail 2006-09-16 03:36:05.974 AO: Broadcasting free space avail 2006-09-16 03:36:05.974 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:05.975 AO: Broadcasting free space avail 2006-09-16 03:36:05.979 NVP: A/V Divergence: -1.3005, Rate: -0.041475, Warpfactor: 0.989586, warpfactor_avg: 0.999925 2006-09-16 03:36:05.989 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:05.990 AO: Broadcasting free space avail 2006-09-16 03:36:05.999 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:06.000 AO: Broadcasting free space avail 2006-09-16 03:36:06.005 AFD: video timecode 550834 550834 6120 6180 2006-09-16 03:36:06.009 AO: audio waiting for buffer to fill: have 2048 want 4096 2006-09-16 03:36:06.009 AO: Broadcasting free space avail 2006-09-16 03:36:06.008 AFD: audio timecode 556203 556203 6180 6204 2006-09-16 03:36:06.011 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=6180 2006-09-16 03:36:06.013 AFD: audio timecode 558363 558363 6204 6228 2006-09-16 03:36:06.013 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=6204 2006-09-16 03:36:06.020 NVP: A/V Divergence: -1.33162, Rate: -0.031125, Warpfactor: 0.989091, warpfactor_avg: 0.999924 2006-09-16 03:36:06.021 AO: Broadcasting free space avail 2006-09-16 03:36:06.021 AO: Broadcasting free space avail 2006-09-16 03:36:06.021 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:06.022 AO: Broadcasting free space avail 2006-09-16 03:36:06.033 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:06.034 AO: Broadcasting free space avail 2006-09-16 03:36:06.041 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:06.042 AO: Broadcasting free space avail 2006-09-16 03:36:06.049 AFD: video timecode 565233 554434 6160 6228 2006-09-16 03:36:06.051 AFD: audio timecode 560523 560523 6228 6252 2006-09-16 03:36:06.051 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=6228 2006-09-16 03:36:06.055 AO: Broadcasting free space avail 2006-09-16 03:36:06.055 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:06.055 AO: Broadcasting free space avail 2006-09-16 03:36:06.058 AFD: audio timecode 562683 562683 6252 6276 2006-09-16 03:36:06.059 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=6252 2006-09-16 03:36:06.061 AO: Broadcasting free space avail 2006-09-16 03:36:06.062 AO: Broadcasting free space avail 2006-09-16 03:36:06.062 AO: Broadcasting free space avail 2006-09-16 03:36:06.071 NVP: A/V Divergence: -1.39245, Rate: -0.060825, Warpfactor: 0.988597, warpfactor_avg: 0.999923 2006-09-16 03:36:06.074 AO: Broadcasting free space avail 2006-09-16 03:36:06.090 AO: Broadcasting free space avail 2006-09-16 03:36:06.093 AFD: video timecode 558033 558033 6200 6276 2006-09-16 03:36:06.095 AFD: audio timecode 564843 564843 6276 6300 2006-09-16 03:36:06.095 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=6276 2006-09-16 03:36:06.103 AO: Broadcasting free space avail 2006-09-16 03:36:06.103 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:06.104 AO: Broadcasting free space avail 2006-09-16 03:36:06.108 NVP: A/V Divergence: -1.45057, Rate: -0.058125, Warpfactor: 0.988102, warpfactor_avg: 0.999922 2006-09-16 03:36:06.109 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:06.110 AO: Broadcasting free space avail 2006-09-16 03:36:06.125 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:06.125 AO: Broadcasting free space avail 2006-09-16 03:36:06.137 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:06.138 AO: Broadcasting free space avail 2006-09-16 03:36:06.138 AFD: video timecode 561633 561633 6240 6300 2006-09-16 03:36:06.139 AFD: audio timecode 567003 567003 6300 6324 2006-09-16 03:36:06.140 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=6300 2006-09-16 03:36:06.143 AFD: audio timecode 569163 569163 6324 6348 2006-09-16 03:36:06.143 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=6324 2006-09-16 03:36:06.150 AO: Broadcasting free space avail 2006-09-16 03:36:06.150 AO: Broadcasting free space avail 2006-09-16 03:36:06.150 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.151 AO: Broadcasting free space avail 2006-09-16 03:36:06.157 NVP: A/V Divergence: -1.55667, Rate: -0.1061, Warpfactor: 0.987608, warpfactor_avg: 0.999922 2006-09-16 03:36:06.159 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.159 AO: Broadcasting free space avail 2006-09-16 03:36:06.173 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.173 AO: Broadcasting free space avail 2006-09-16 03:36:06.181 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.181 AO: Broadcasting free space avail 2006-09-16 03:36:06.186 AFD: video timecode 576033 565233 6280 6348 2006-09-16 03:36:06.188 AFD: audio timecode 571323 571323 6348 6372 2006-09-16 03:36:06.188 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=6348 2006-09-16 03:36:06.193 AFD: audio timecode 573483 573483 6372 6396 2006-09-16 03:36:06.194 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=6372 2006-09-16 03:36:06.196 AO: Broadcasting free space avail 2006-09-16 03:36:06.196 AO: Broadcasting free space avail 2006-09-16 03:36:06.197 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:06.197 AO: Broadcasting free space avail 2006-09-16 03:36:06.198 NVP: A/V Divergence: -1.53, Rate: 0.026675, Warpfactor: 0.987896, warpfactor_avg: 0.999921 2006-09-16 03:36:06.213 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:06.213 AO: Broadcasting free space avail 2006-09-16 03:36:06.224 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:06.225 AO: Broadcasting free space avail 2006-09-16 03:36:06.225 AFD: video timecode 568833 568833 6320 6396 2006-09-16 03:36:06.229 AFD: audio timecode 575643 575643 6396 6420 2006-09-16 03:36:06.230 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=6396 2006-09-16 03:36:06.238 AO: Broadcasting free space avail 2006-09-16 03:36:06.238 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:06.239 AO: Broadcasting free space avail 2006-09-16 03:36:06.246 AO: audio waiting for buffer to fill: have 3072 want 4096 2006-09-16 03:36:06.247 AO: Broadcasting free space avail 2006-09-16 03:36:06.254 AFD: video timecode 572433 572433 6360 6420 2006-09-16 03:36:06.256 AFD: audio timecode 577803 577803 6420 6444 2006-09-16 03:36:06.256 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=6420 2006-09-16 03:36:06.258 AFD: audio timecode 579963 579963 6444 6468 2006-09-16 03:36:06.258 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=6444 2006-09-16 03:36:06.260 AO: Broadcasting free space avail 2006-09-16 03:36:06.261 AO: Broadcasting free space avail 2006-09-16 03:36:06.261 AO: Broadcasting free space avail 2006-09-16 03:36:06.261 AO: Broadcasting free space avail 2006-09-16 03:36:06.265 NVP: A/V Divergence: -1.69125, Rate: -0.16125, Warpfactor: 0.987402, warpfactor_avg: 0.99992 2006-09-16 03:36:06.275 AO: Broadcasting free space avail 2006-09-16 03:36:06.286 AO: Broadcasting free space avail 2006-09-16 03:36:06.293 AFD: video timecode 586833 576033 6400 6468 2006-09-16 03:36:06.296 AFD: audio timecode 582123 582123 6468 6492 2006-09-16 03:36:06.296 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=6468 2006-09-16 03:36:06.297 AO: Broadcasting free space avail 2006-09-16 03:36:06.297 AO: audio waiting for buffer to fill: have 512 want 4096 2006-09-16 03:36:06.297 AO: Broadcasting free space avail 2006-09-16 03:36:06.302 AFD: audio timecode 584283 584283 6492 6516 2006-09-16 03:36:06.302 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=6492 2006-09-16 03:36:06.308 NVP: A/V Divergence: -1.82467, Rate: -0.133425, Warpfactor: 0.986908, warpfactor_avg: 0.999919 2006-09-16 03:36:06.311 AO: Broadcasting free space avail 2006-09-16 03:36:06.312 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:06.312 AO: Broadcasting free space avail 2006-09-16 03:36:06.317 NVP: A/V Divergence: -1.831, Rate: -0.006325, Warpfactor: 0.986415, warpfactor_avg: 0.999918 2006-09-16 03:36:06.323 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:06.324 AO: Broadcasting free space avail 2006-09-16 03:36:06.334 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:06.334 AO: Broadcasting free space avail 2006-09-16 03:36:06.343 AO: audio waiting for buffer to fill: have 1024 want 4096 2006-09-16 03:36:06.343 AO: Broadcasting free space avail 2006-09-16 03:36:06.345 AFD: video timecode 579633 579633 6440 6516 2006-09-16 03:36:06.347 AFD: audio timecode 586443 586443 6516 6540 2006-09-16 03:36:06.347 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=6516 2006-09-16 03:36:06.349 AO: Broadcasting free space avail 2006-09-16 03:36:06.350 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.350 AO: Broadcasting free space avail 2006-09-16 03:36:06.365 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.366 AO: Broadcasting free space avail 2006-09-16 03:36:06.374 AO: audio waiting for buffer to fill: have 1536 want 4096 2006-09-16 03:36:06.375 AO: Broadcasting free space avail 2006-09-16 03:36:06.377 AFD: video timecode 583233 583233 6480 6540 2006-09-16 03:36:06.380 AFD: audio timecode 588603 588603 6540 6564 2006-09-16 03:36:06.381 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=6540 2006-09-16 03:36:06.386 AFD: audio timecode 590763 590763 6564 6588 2006-09-16 03:36:06.387 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=6564 2006-09-16 03:36:06.387 AFD: positionMap[ 11 ] == 5056550. 2006-09-16 03:36:06.393 AO: Broadcasting free space avail 2006-09-16 03:36:06.393 AO: Broadcasting free space avail 2006-09-16 03:36:06.394 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:06.394 AO: Broadcasting free space avail 2006-09-16 03:36:06.395 NVP: A/V Divergence: -1.97325, Rate: -0.14225, Warpfactor: 0.985922, warpfactor_avg: 0.999917 2006-09-16 03:36:06.407 NVP: A/V Divergence: -1.99242, Rate: -0.019175, Warpfactor: 0.985429, warpfactor_avg: 0.999916 2006-09-16 03:36:06.410 AO: audio waiting for buffer to fill: have 2560 want 4096 2006-09-16 03:36:06.411 AO: Broadcasting free space avail 2006-09-16 03:36:06.414 NVP: prebuffering pause 2006-09-16 03:36:06.415 NVP: Waiting for prebuffer.. 0 LAAAAAADAAADAAAL 2006-09-16 03:36:06.421 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:06.421 AO: 59328 bytes free on soundcard 2006-09-16 03:36:06.436 AO: 62048 bytes free on soundcard 2006-09-16 03:36:06.439 TV: Attempting to change from WatchingLiveTV to None 2006-09-16 03:36:06.444 TV: StopStuff() -- begin 2006-09-16 03:36:06.444 TV: StopStuff(): stopping ring buffer[s] 2006-09-16 03:36:06.445 AFD: video timecode 597633 586833 6520 6588 2006-09-16 03:36:06.447 AFD: audio timecode 592923 592923 6588 6612 2006-09-16 03:36:06.447 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=6588 2006-09-16 03:36:06.449 AO: 60512 bytes free on soundcard 2006-09-16 03:36:06.451 AFD: audio timecode 595082 595082 6612 6636 2006-09-16 03:36:06.452 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=6612 2006-09-16 03:36:06.461 AO: 62912 bytes free on soundcard 2006-09-16 03:36:06.470 NVP: A/V Divergence: -1.83805, Rate: 0.154375, Warpfactor: 0.985921, warpfactor_avg: 0.999915 2006-09-16 03:36:06.472 AO: OutputAudioLoop: Play Event 2006-09-16 03:36:06.473 TV: StopStuff(): stopping player[s] (1/2) 2006-09-16 03:36:06.473 TV: StopStuff(): stopping recorder[s] 2006-09-16 03:36:06.474 write -> 15 32 QUERY_RECORDER 1[]:[]STOP_LIVETV 2006-09-16 03:36:06.472 AO: Broadcasting free space avail 2006-09-16 03:36:06.474 AO: Broadcasting free space avail 2006-09-16 03:36:06.480 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:06.481 AO: Broadcasting free space avail 2006-09-16 03:36:06.485 AO: audio waiting for buffer to fill: have 3584 want 4096 2006-09-16 03:36:06.486 AO: Broadcasting free space avail 2006-09-16 03:36:06.489 NVP: prebuffering pause 2006-09-16 03:36:06.489 NVP: Waiting for prebuffer.. 0 LAAAAAAAAAAAALAd 2006-09-16 03:36:06.493 AO: OutputAudioLoop: audio paused 2006-09-16 03:36:06.493 AO: 56736 bytes free on soundcard 2006-09-16 03:36:06.501 AO: 58240 bytes free on soundcard 2006-09-16 03:36:06.509 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:36:06.509 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:36:06.510 read <- 11 49 BACKEND_MESSAGE[]:[]DONE_RECORDING 1 10[]:[]empty 2006-09-16 03:36:06.510 AO: 59936 bytes free on soundcard 2006-09-16 03:36:06.511 AFD: video timecode 590433 590433 6560 6636 2006-09-16 03:36:06.513 AFD: audio timecode 597242 597242 6636 6660 2006-09-16 03:36:06.513 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=6636 2006-09-16 03:36:06.514 NVP: Exited decoder loop. 2006-09-16 03:36:06.517 AO: 61376 bytes free on soundcard 2006-09-16 03:36:06.525 VideoOutputXv: dtor 2006-09-16 03:36:06.525 VideoOutputXv: DiscardFrames(1) 2006-09-16 03:36:06.525 VideoOutputXv: DiscardFrames() 1: LAAAAAAAAAAAAuAd 2006-09-16 03:36:06.526 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding. 2006-09-16 03:36:06.526 VideoOutputXv: DiscardFrames() 2: DAAAAAAAAAAAAdAd 2006-09-16 03:36:06.526 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding. 2006-09-16 03:36:06.526 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding. 2006-09-16 03:36:06.527 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAdAd -- done() 2006-09-16 03:36:06.527 VideoOutputXv: DiscardFrames(1) 2006-09-16 03:36:06.527 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAdAd 2006-09-16 03:36:06.528 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding. 2006-09-16 03:36:06.528 VideoOutputXv: DiscardFrames() 2: AAAAAAAAAAAAAdAd 2006-09-16 03:36:06.528 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding. 2006-09-16 03:36:06.528 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAdAd -- done() 2006-09-16 03:36:06.529 AO: 63584 bytes free on soundcard 2006-09-16 03:36:06.537 AO: 61024 bytes free on soundcard 2006-09-16 03:36:06.540 VideoOutputXv: Closing XVideo port 68 2006-09-16 03:36:06.542 AO: Killing AudioOutputDSP 2006-09-16 03:36:06.545 AO: OutputAudioLoop: Stop Event 2006-09-16 03:36:06.545 AO: kickoffOutputAudioLoop exiting 2006-09-16 03:36:06.679 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:36:06.680 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:36:06.680 read <- 11 78 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr-2006-09-16T03:35... 2006-09-16 03:36:06.695 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:36:06.695 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:36:06.696 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:36:06.698 read <- 15 2 ok 2006-09-16 03:36:06.698 TV: StopStuff(): stopping player[s] (2/2) 2006-09-16 03:36:06.702 write -> 12 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2006-09-16 03:36:06.704 read <- 12 2 OK 2006-09-16 03:36:06.705 MythSocket(852b5e8:11): socket is readable 2006-09-16 03:36:06.705 MythSocket(852b5e8:11): cb->readyRead() 2006-09-16 03:36:06.706 read <- 11 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2006-09-16 03:36:06.810 MythSocket(85392f8:15): DownRef: -1 2006-09-16 03:36:06.811 MythSocket(85392f8:15): state change Connected -> Idle 2006-09-16 03:36:06.812 MythSocket(85392f8:-1): delete socket 2006-09-16 03:36:06.825 TV: StopStuff() -- end 2006-09-16 03:36:06.825 TV: Changing from WatchingLiveTV to None 2006-09-16 03:36:06.842 DPMS Reactivated. 2006-09-16 03:36:09.789 write -> 12 31 QUERY_IS_ACTIVE_BACKEND[]:[]pvr 2006-09-16 03:36:09.791 read <- 12 4 TRUE 2006-09-16 03:36:11.698 MythSocket(84feee8:12): DownRef: -1 2006-09-16 03:36:11.699 MythSocket(84feee8:12): state change Connected -> Idle 2006-09-16 03:36:11.699 MythSocket(84feee8:-1): delete socket 2006-09-16 03:36:11.699 MythSocket(852b5e8:11): DownRef: 0 2006-09-16 03:36:11.706 MythSocket(852b5e8:11): DownRef: -1 2006-09-16 03:36:11.706 MythSocket(852b5e8:11): state change Connected -> Idle 2006-09-16 03:36:11.706 MythSocket(852b5e8:-1): delete socket 2006-09-16 03:36:11.866 MythSocket: readyread thread exit