Ticket #2396: myth_badresolution.txt

File myth_badresolution.txt, 188.4 KB (added by anonymous, 18 years ago)

Recorded at 720x576, showing no video

Line 
12006-09-16 03:35:36.729 Using runtime prefix = /usr/local
22006-09-16 03:35:36.763 DPMS is active.
32006-09-16 03:35:36.822 New DB connection, total: 1
42006-09-16 03:35:36.851 Connected to database 'mythconverg' at host: localhost
52006-09-16 03:35:36.858 Total desktop dim: 720x576, with 1 screen[s].
62006-09-16 03:35:36.867 Using screen 0, 720x576 at 0,0
72006-09-16 03:35:37.124 user: 1000 effective user: 1000 before privileged thread
82006-09-16 03:35:37.126 user: 1000 effective user: 1000 after privileged thread
92006-09-16 03:35:37.130 Current Schema Version: 1160
102006-09-16 03:35:37.132 mythfrontend version: 0.20.20060828-3 www.mythtv.org
112006-09-16 03:35:37.133 Enabled verbose msgs: all nodatabase
122006-09-16 03:35:37.163 user: 1000 effective user: 1000 run_priv_thread
132006-09-16 03:35:37.804 max_width: 720 max_height: 576
142006-09-16 03:35:38.154 Total desktop dim: 720x576, with 1 screen[s].
152006-09-16 03:35:38.158 Using screen 0, 720x576 at 0,0
162006-09-16 03:35:38.160 Switching to square mode (Titivillus)
17__driCreateNewScreen_20050727 - succeeded
182006-09-16 03:35:38.301 Using the Qt painter
192006-09-16 03:35:39.327 /home/mythtv/.mythtv/joystickmenurc not found.
202006-09-16 03:35:39.328 Joystick disabled.
212006-09-16 03:35:39.330 New DB connection, total: 2
222006-09-16 03:35:39.332 Connected to database 'mythconverg' at host: localhost
232006-09-16 03:35:39.667 Loading from: /usr/local/share/mythtv/themes/default/base.xml
242006-09-16 03:35:41.405 Registering Internal as a media playback plugin.
252006-09-16 03:35:41.535 Registering MythDVD DVD Media Handler as a media handler ext()
262006-09-16 03:35:41.539 Registering MythDVD VCD Media Handler as a media handler ext()
272006-09-16 03:35:41.934 generating cache image for: /usr/local/share/mythtv/themes/default/blank.png
282006-09-16 03:35:54.630 MythSocket(852b5e8:11): new socket
292006-09-16 03:35:54.641 Connecting to backend server: 192.168.178.31:6543 (try 1 of 5)
302006-09-16 03:35:54.642 MythSocket(84feee8:12): new socket
312006-09-16 03:35:54.642 MythSocket(84feee8:12): attempting connect() to (192.168.178.31:6543)
322006-09-16 03:35:54.643 MythSocket(84feee8:12): state change Idle -> Connected
332006-09-16 03:35:54.644 write -> 12 21      MYTH_PROTO_VERSION 30
342006-09-16 03:35:54.647 read  <- 12 13      ACCEPT[]:[]30
352006-09-16 03:35:54.647 Using protocol version 30
362006-09-16 03:35:54.647 write -> 12 17      ANN Monitor pvr 0
372006-09-16 03:35:54.649 read  <- 12 2       OK
382006-09-16 03:35:54.649 MythSocket(852b5e8:11): attempting connect() to (192.168.178.31:6543)
392006-09-16 03:35:54.650 MythSocket(852b5e8:11): state change Idle -> Connected
402006-09-16 03:35:54.650 write -> 11 17      ANN Monitor pvr 1
412006-09-16 03:35:54.655 read  <- 11 2       OK
422006-09-16 03:35:54.656 MythSocket(852b5e8:11): UpRef: 1
432006-09-16 03:35:54.656 write -> 12 29      GET_NEXT_FREE_RECORDER[]:[]-1
442006-09-16 03:35:54.657 MythSocket: readyread thread start
452006-09-16 03:35:54.658 read  <- 12 29      1[]:[]192.168.178.31[]:[]6543
462006-09-16 03:35:54.660 TV: Attempting to change from None to WatchingLiveTV
472006-09-16 03:35:54.661 MythSocket(85392f8:15): new socket
482006-09-16 03:35:54.661 MythSocket(85392f8:15): attempting connect() to (192.168.178.31:6543)
492006-09-16 03:35:54.666 MythSocket(85392f8:15): state change Idle -> Connected
502006-09-16 03:35:54.667 write -> 15 21      MYTH_PROTO_VERSION 30
512006-09-16 03:35:54.671 read  <- 15 13      ACCEPT[]:[]30
522006-09-16 03:35:54.672 Using protocol version 30
532006-09-16 03:35:54.672 write -> 15 18      ANN Playback pvr 0
542006-09-16 03:35:54.677 read  <- 15 2       OK
552006-09-16 03:35:54.677 write -> 15 72      QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-pvr-2006-09-16T03:35:5...
562006-09-16 03:35:55.215 MythSocket(852b5e8:11): socket is readable
572006-09-16 03:35:55.215 MythSocket(852b5e8:11): cb->readyRead()
582006-09-16 03:35:55.216 read  <- 11 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
592006-09-16 03:35:55.243 MythSocket(852b5e8:11): socket is readable
602006-09-16 03:35:55.244 MythSocket(852b5e8:11): cb->readyRead()
612006-09-16 03:35:55.244 read  <- 11 78      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr-2006-09-16T03:35...
622006-09-16 03:35:55.279 read  <- 15 2       ok
632006-09-16 03:35:55.284 LiveTVChain(live-pvr-2006-09-16T03:35:54): ReloadAll(): Added new recording
642006-09-16 03:35:55.295 RingBuf(/mnt/recordings/7_20060916033555.mpg): OpenFile(/mnt/recordings/7_20060916033555.mpg, 12)
652006-09-16 03:35:56.017 RingBuf(/mnt/recordings/7_20060916033555.mpg): CalcReadAheadThresh(4000 KB)
66                         -> threshhold(146 KB) min read(32 KB) blk size(64 KB)
672006-09-16 03:35:56.018 write -> 15 33      QUERY_RECORDER 1[]:[]IS_RECORDING
682006-09-16 03:35:56.021 read  <- 15 1       1
692006-09-16 03:35:56.021 write -> 15 33      QUERY_RECORDER 1[]:[]IS_RECORDING
702006-09-16 03:35:56.029 read  <- 15 1       1
712006-09-16 03:35:56.030 TV: StartRecorder(): took 12 ms to start recorder.
722006-09-16 03:35:56.030 write -> 15 34      QUERY_RECORDER 1[]:[]GET_FRAMERATE
732006-09-16 03:35:56.032 read  <- 15 2       25
742006-09-16 03:35:56.056 DPMS Deactivated
752006-09-16 03:35:56.084 write -> 12 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
762006-09-16 03:35:56.095 read  <- 12 2       OK
772006-09-16 03:35:56.110 MythSocket(852b5e8:11): socket is readable
782006-09-16 03:35:56.111 MythSocket(852b5e8:11): cb->readyRead()
792006-09-16 03:35:56.111 read  <- 11 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
802006-09-16 03:35:56.171 TV: Output filters for this channel are: ''
812006-09-16 03:35:56.261 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 6000000 at 0x0x84ffbf0
822006-09-16 03:35:56.280 VideoOutputXv: XvMC version: 1.1
832006-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)
842006-09-16 03:35:56.282 Trying XvMC port 68
852006-09-16 03:35:56.291 Found a suitable XvMC surface 0
862006-09-16 03:35:56.292 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask
872006-09-16 03:35:56.293 VideoOutputXv: Adaptor#0: XV_SWOV has flag[s]: XvInputMask XvImageMask
882006-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)
892006-09-16 03:35:56.293 Trying XvMC port 68
902006-09-16 03:35:56.294 Found a suitable XvMC surface 0
912006-09-16 03:35:56.294 VideoOutputXv: Grabbed xv port 68
922006-09-16 03:35:56.295 VideoOutputXv: XvMC surface found with VLD support on port 68
932006-09-16 03:35:56.303 VideoOutputXv: Closing XVideo port 68
942006-09-16 03:35:56.305 detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
952006-09-16 03:35:56.305 AFD: Looking for decoder for MPEG2VIDEO_XVMC_VLD
962006-09-16 03:35:56.305 AFD: Opened codec 0x8e88ff0, id(MPEG2VIDEO_XVMC_VLD) type(Video)
972006-09-16 03:35:56.306 AFD: Stream #1, has id 0x448 codec id MP2, type Audio, bitrate 224000 at 0x0x8549ed0
982006-09-16 03:35:56.306 AFD: Looking for decoder for MP2
992006-09-16 03:35:56.466 AFD: Opened codec 0x8e89340, id(MP2) type(Audio)
1002006-09-16 03:35:56.467 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Unknown language(0).
1012006-09-16 03:35:56.485 RingBuf(/mnt/recordings/7_20060916033555.mpg): CalcReadAheadThresh(6224 KB)
102                         -> threshhold(227 KB) min read(32 KB) blk size(128 KB)
1032006-09-16 03:35:56.485 AFD: Selected track 1: Unknown MP2 2ch (A/V Stream #1)
1042006-09-16 03:35:56.486 AFD: Initializing audio parms from audio track #1
1052006-09-16 03:35:56.486 AFD: Audio format changed
106                        from id(NONE)    -1Hz -1ch   0bps    ; id(NONE)    -1Hz -1ch   0bps   
107                        to   id( MP2) 48000Hz  2ch  16bps    ; id( MP2) 48000Hz  2ch  16bps   
1082006-09-16 03:35:56.503 AO: Killing AudioOutputDSP
1092006-09-16 03:35:56.504 Opening OSS audio device '/dev/dsp'.
1102006-09-16 03:35:56.523 AO: Audio fragment size: 4096
1112006-09-16 03:35:56.531 AO: Audio Stretch Factor: 1
1122006-09-16 03:35:56.531 AO: Ending reconfigure
1132006-09-16 03:35:56.532 Dec: Trying to select track (w/lang)
1142006-09-16 03:35:56.532 Dec: Selecting first track
1152006-09-16 03:35:56.533 Dec: Selected track #1 in the Unknown language(0)
1162006-09-16 03:35:56.533 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
1172006-09-16 03:35:56.541 Position map filled from DB to: 1
1182006-09-16 03:35:56.541 SyncPositionMap watchingrecording, from DB: 1 entries
1192006-09-16 03:35:56.542 write -> 15 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
1202006-09-16 03:35:56.543 read  <- 15 8       0[]:[]12
1212006-09-16 03:35:56.543 Filling position map from 2 to 1
1222006-09-16 03:35:56.544 write -> 15 50      QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]2[]:[]1
1232006-09-16 03:35:56.545 read  <- 15 2       ok
1242006-09-16 03:35:56.545 Position map filled from Encoder to: 1
1252006-09-16 03:35:56.545 SyncPositionMap watchingrecording total: 1 entries
1262006-09-16 03:35:56.546 SyncPositionMap, new totframes: 12, new length: 0, posMap size: 1
127Input #0, mpeg, from '/mnt/recordings/7_20060916033555.mpg':
128  Duration: N/A, bitrate: N/A
129  Stream #0.0[0x1e0], 1/90000: Video: mpegvideo_xvmc_vld, yuv420p, 720x576, 1/25, 6000 kb/s, 25.00 fps(r)
130  Stream #0.1[0x1c0], 1/90000: Audio: mp2, 48000 Hz, stereo, 224 kb/s
1312006-09-16 03:35:56.546 AFD: Partial position map found
1322006-09-16 03:35:56.547 AFD: Successfully opened decoder for file: "/mnt/recordings/7_20060916033555.mpg". novideo(0)
1332006-09-16 03:35:56.552 AO: kickoffOutputAudioLoop: pid = 8653
1342006-09-16 03:35:56.553 AO: OutputAudioLoop: Play Event
1352006-09-16 03:35:56.553 AO: Broadcasting free space avail
1362006-09-16 03:35:56.597 AO: OutputAudioLoop: audio paused
1372006-09-16 03:35:56.597 AO: 65536 bytes free on soundcard
1382006-09-16 03:35:56.606 AO: 63040 bytes free on soundcard
1392006-09-16 03:35:56.614 AO: 60416 bytes free on soundcard
1402006-09-16 03:35:56.621 AO: 61856 bytes free on soundcard
1412006-09-16 03:35:56.630 AO: 59392 bytes free on soundcard
1422006-09-16 03:35:56.638 AO: 61088 bytes free on soundcard
1432006-09-16 03:35:56.639 VideoOutputXv: ctor
1442006-09-16 03:35:56.642 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
1452006-09-16 03:35:56.642 Snapping height to avoid scaling: height: 576, top: 0
1462006-09-16 03:35:56.643 Snapping width to avoid scaling: width: 720, left: 0
1472006-09-16 03:35:56.643 Display Rect  left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
1482006-09-16 03:35:56.643 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
1492006-09-16 03:35:56.644 VideoOutputXv: Pixel dimensions: Screen 720x576, window 720x576
1502006-09-16 03:35:56.649 AO: 63072 bytes free on soundcard
1512006-09-16 03:35:56.649 VideoOutputXv: Estimated display dimensions: 244x195 mm  Aspect: 1.25128
1522006-09-16 03:35:56.650 VideoOutputXv: Estimated window dimensions: 244x195 mm  Aspect: 1.25128
1532006-09-16 03:35:56.653 VideoOutputXv: XvMCTex: Init failed
1542006-09-16 03:35:56.654 VideoOutputXv: Number of bits per pixel is 16,
155                        but we only support ARGB 32 bbp for ChromaKeyOSD.
1562006-09-16 03:35:56.654 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask
1572006-09-16 03:35:56.655 VideoOutputXv: Adaptor#0: XV_SWOV has flag[s]: XvInputMask XvImageMask
1582006-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)
1592006-09-16 03:35:56.655 Trying XvMC port 68
1602006-09-16 03:35:56.656 Found a suitable XvMC surface 0
1612006-09-16 03:35:56.657 AO: 60608 bytes free on soundcard
1622006-09-16 03:35:56.658 VideoOutputXv: Grabbed xv port 68
1632006-09-16 03:35:56.659 VideoOutputXv: XvMC surface found with VLD support on port 68
1642006-09-16 03:35:56.659 VideoOutputXv: XvMC Adaptor Name: 'XV_SWOV'
1652006-09-16 03:35:56.666 AO: 62208 bytes free on soundcard
1662006-09-16 03:35:56.673 AO: 59584 bytes free on soundcard
1672006-09-16 03:35:56.683 AO: 61536 bytes free on soundcard
1682006-09-16 03:35:56.689 AO: 58624 bytes free on soundcard
1692006-09-16 03:35:56.699 AO: 60384 bytes free on soundcard
1702006-09-16 03:35:56.694 Snapping height to avoid scaling: height: 576, top: 0
1712006-09-16 03:35:56.700 Snapping width to avoid scaling: width: 720, left: 0
1722006-09-16 03:35:56.700 Display Rect  left: 0, top: 0, width: 720, height: 576, aspect: 1.25128
1732006-09-16 03:35:56.700 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
1742006-09-16 03:35:56.705 AO: 61664 bytes free on soundcard
1752006-09-16 03:35:56.713 AO: 59104 bytes free on soundcard
1762006-09-16 03:35:56.722 AO: 60704 bytes free on soundcard
1772006-09-16 03:35:56.729 AO: 62176 bytes free on soundcard
1782006-09-16 03:35:56.736 AO: 59424 bytes free on soundcard
1792006-09-16 03:35:56.744 AO: 60960 bytes free on soundcard
1802006-09-16 03:35:56.752 AO: 62496 bytes free on soundcard
1812006-09-16 03:35:56.761 AO: 60064 bytes free on soundcard
1822006-09-16 03:35:56.771 AO: 61952 bytes free on soundcard
1832006-09-16 03:35:56.777 AO: 59008 bytes free on soundcard
1842006-09-16 03:35:56.785 AO: 60512 bytes free on soundcard
1852006-09-16 03:35:56.792 AO: 61984 bytes free on soundcard
1862006-09-16 03:35:56.802 AO: 59808 bytes free on soundcard
1872006-09-16 03:35:56.809 AO: 61024 bytes free on soundcard
1882006-09-16 03:35:56.820 AO: 63136 bytes free on soundcard
1892006-09-16 03:35:56.824 AO: 59936 bytes free on soundcard
1902006-09-16 03:35:56.836 AO: 62112 bytes free on soundcard
1912006-09-16 03:35:56.840 AO: 58912 bytes free on soundcard
1922006-09-16 03:35:56.848 AO: 60448 bytes free on soundcard
1932006-09-16 03:35:56.856 AO: 62016 bytes free on soundcard
1942006-09-16 03:35:56.864 AO: 59424 bytes free on soundcard
1952006-09-16 03:35:56.873 AO: 61056 bytes free on soundcard
1962006-09-16 03:35:56.880 AO: 62528 bytes free on soundcard
1972006-09-16 03:35:56.888 AO: 59936 bytes free on soundcard
1982006-09-16 03:35:56.896 AO: 61504 bytes free on soundcard
1992006-09-16 03:35:56.906 AO: 59264 bytes free on soundcard
2002006-09-16 03:35:56.913 AO: 60512 bytes free on soundcard
2012006-09-16 03:35:56.924 AO: 62720 bytes free on soundcard
2022006-09-16 03:35:56.932 AO: 60192 bytes free on soundcard
2032006-09-16 03:35:56.941 AO: 61792 bytes free on soundcard
2042006-09-16 03:35:56.948 AO: 59168 bytes free on soundcard
2052006-09-16 03:35:56.959 AO: 61312 bytes free on soundcard
2062006-09-16 03:35:56.965 AO: 62304 bytes free on soundcard
2072006-09-16 03:35:56.975 AO: 60288 bytes free on soundcard
2082006-09-16 03:35:56.980 AO: 61248 bytes free on soundcard
2092006-09-16 03:35:56.994 AO: 63776 bytes free on soundcard
2102006-09-16 03:35:57.000 AO: 60960 bytes free on soundcard
2112006-09-16 03:35:57.010 AO: 62784 bytes free on soundcard
2122006-09-16 03:35:57.017 AO: 60000 bytes free on soundcard
2132006-09-16 03:35:57.029 AO: 62336 bytes free on soundcard
2142006-09-16 03:35:57.036 AO: 59680 bytes free on soundcard
2152006-09-16 03:35:57.046 AO: 61472 bytes free on soundcard
2162006-09-16 03:35:57.052 AO: 58656 bytes free on soundcard
2172006-09-16 03:35:57.061 AO: 60384 bytes free on soundcard
2182006-09-16 03:35:57.068 AO: 61760 bytes free on soundcard
2192006-09-16 03:35:57.077 AO: 59360 bytes free on soundcard
2202006-09-16 03:35:57.086 AO: 60992 bytes free on soundcard
2212006-09-16 03:35:57.096 AO: 62944 bytes free on soundcard
2222006-09-16 03:35:57.104 AO: 60480 bytes free on soundcard
2232006-09-16 03:35:57.112 AO: 62016 bytes free on soundcard
2242006-09-16 03:35:57.121 AO: 59488 bytes free on soundcard
2252006-09-16 03:35:57.128 AO: 60992 bytes free on soundcard
2262006-09-16 03:35:57.136 AO: 62528 bytes free on soundcard
2272006-09-16 03:35:57.144 AO: 59968 bytes free on soundcard
2282006-09-16 03:35:57.155 AO: 62080 bytes free on soundcard
2292006-09-16 03:35:57.160 AO: 58944 bytes free on soundcard
2302006-09-16 03:35:57.168 AO: 60480 bytes free on soundcard
2312006-09-16 03:35:57.176 AO: 62016 bytes free on soundcard
2322006-09-16 03:35:57.184 AO: 59456 bytes free on soundcard
2332006-09-16 03:35:57.192 AO: 60992 bytes free on soundcard
2342006-09-16 03:35:57.200 AO: 62528 bytes free on soundcard
2352006-09-16 03:35:57.208 AO: 59968 bytes free on soundcard
2362006-09-16 03:35:57.216 AO: 61504 bytes free on soundcard
2372006-09-16 03:35:57.225 AO: 59072 bytes free on soundcard
2382006-09-16 03:35:57.234 AO: 60480 bytes free on soundcard
2392006-09-16 03:35:57.242 AO: 62240 bytes free on soundcard
2402006-09-16 03:35:57.249 AO: 59488 bytes free on soundcard
2412006-09-16 03:35:57.260 AO: 61664 bytes free on soundcard
2422006-09-16 03:35:57.268 AO: 59168 bytes free on soundcard
2432006-09-16 03:35:57.276 AO: 60704 bytes free on soundcard
2442006-09-16 03:35:57.281 OSD: InitCC708() -- begin
2452006-09-16 03:35:57.285 AO: 62304 bytes free on soundcard
2462006-09-16 03:35:57.295 AO: 60192 bytes free on soundcard
2472006-09-16 03:35:57.300 AO: 61216 bytes free on soundcard
2482006-09-16 03:35:57.311 AO: 63296 bytes free on soundcard
2492006-09-16 03:35:57.316 AO: 60224 bytes free on soundcard
2502006-09-16 03:35:57.324 AO: 61760 bytes free on soundcard
2512006-09-16 03:35:57.333 AO: 59328 bytes free on soundcard
2522006-09-16 03:35:57.340 AO: 60736 bytes free on soundcard
2532006-09-16 03:35:57.349 AO: 62336 bytes free on soundcard
2542006-09-16 03:35:57.358 AO: 60064 bytes free on soundcard
2552006-09-16 03:35:57.366 AO: 61568 bytes free on soundcard
2562006-09-16 03:35:57.372 AO: 58688 bytes free on soundcard
2572006-09-16 03:35:57.380 AO: 60224 bytes free on soundcard
2582006-09-16 03:35:57.388 AO: 61760 bytes free on soundcard
2592006-09-16 03:35:57.397 AO: 59232 bytes free on soundcard
2602006-09-16 03:35:57.406 AO: 61088 bytes free on soundcard
2612006-09-16 03:35:57.412 AO: 62272 bytes free on soundcard
2622006-09-16 03:35:57.424 AO: 60512 bytes free on soundcard
2632006-09-16 03:35:57.433 AO: 62112 bytes free on soundcard
2642006-09-16 03:35:57.441 AO: 59488 bytes free on soundcard
2652006-09-16 03:35:57.449 AO: 61056 bytes free on soundcard
2662006-09-16 03:35:57.457 AO: 62560 bytes free on soundcard
2672006-09-16 03:35:57.465 AO: 60032 bytes free on soundcard
2682006-09-16 03:35:57.476 AO: 62176 bytes free on soundcard
2692006-09-16 03:35:57.484 AO: 59744 bytes free on soundcard
2702006-09-16 03:35:57.492 AO: 61248 bytes free on soundcard
2712006-09-16 03:35:57.500 AO: 62784 bytes free on soundcard
2722006-09-16 03:35:57.510 AO: 60480 bytes free on soundcard
2732006-09-16 03:35:57.516 AO: 61792 bytes free on soundcard
2742006-09-16 03:35:57.529 AO: 60160 bytes free on soundcard
2752006-09-16 03:35:57.537 AO: 61568 bytes free on soundcard
2762006-09-16 03:35:57.545 AO: 59168 bytes free on soundcard
2772006-09-16 03:35:57.552 AO: 60480 bytes free on soundcard
2782006-09-16 03:35:57.561 AO: 62208 bytes free on soundcard
2792006-09-16 03:35:57.568 AO: 59456 bytes free on soundcard
2802006-09-16 03:35:57.578 AO: 61376 bytes free on soundcard
2812006-09-16 03:35:57.585 AO: 62656 bytes free on soundcard
2822006-09-16 03:35:57.593 AO: 60032 bytes free on soundcard
2832006-09-16 03:35:57.600 AO: 61504 bytes free on soundcard
2842006-09-16 03:35:57.609 AO: 59040 bytes free on soundcard
2852006-09-16 03:35:57.616 AO: 60480 bytes free on soundcard
2862006-09-16 03:35:57.625 AO: 62112 bytes free on soundcard
2872006-09-16 03:35:57.632 AO: 59488 bytes free on soundcard
2882006-09-16 03:35:57.641 AO: 61088 bytes free on soundcard
2892006-09-16 03:35:57.648 AO: 62528 bytes free on soundcard
2902006-09-16 03:35:57.657 AO: 60032 bytes free on soundcard
2912006-09-16 03:35:57.667 AO: 62048 bytes free on soundcard
2922006-09-16 03:35:57.673 AO: 59040 bytes free on soundcard
2932006-09-16 03:35:57.684 AO: 61280 bytes free on soundcard
2942006-09-16 03:35:57.692 AO: 62784 bytes free on soundcard
2952006-09-16 03:35:57.701 AO: 60416 bytes free on soundcard
2962006-09-16 03:35:57.708 AO: 61792 bytes free on soundcard
2972006-09-16 03:35:57.716 AO: 59200 bytes free on soundcard
2982006-09-16 03:35:57.725 AO: 60768 bytes free on soundcard
2992006-09-16 03:35:57.732 AO: 62304 bytes free on soundcard
3002006-09-16 03:35:57.740 AO: 59744 bytes free on soundcard
3012006-09-16 03:35:57.748 AO: 61248 bytes free on soundcard
3022006-09-16 03:35:57.756 AO: 62816 bytes free on soundcard
3032006-09-16 03:35:57.767 AO: 60736 bytes free on soundcard
3042006-09-16 03:35:57.772 AO: 61760 bytes free on soundcard
3052006-09-16 03:35:57.783 AO: 59744 bytes free on soundcard
3062006-09-16 03:35:57.788 AO: 60736 bytes free on soundcard
3072006-09-16 03:35:57.796 AO: 62272 bytes free on soundcard
3082006-09-16 03:35:57.804 AO: 59744 bytes free on soundcard
3092006-09-16 03:35:57.812 AO: 61248 bytes free on soundcard
3102006-09-16 03:35:57.820 AO: 62784 bytes free on soundcard
3112006-09-16 03:35:57.829 AO: 60256 bytes free on soundcard
3122006-09-16 03:35:57.837 AO: 61888 bytes free on soundcard
3132006-09-16 03:35:57.844 AO: 59200 bytes free on soundcard
3142006-09-16 03:35:57.855 AO: 61152 bytes free on soundcard
3152006-09-16 03:35:57.860 AO: 62272 bytes free on soundcard
3162006-09-16 03:35:57.871 AO: 60160 bytes free on soundcard
3172006-09-16 03:35:57.876 AO: 61280 bytes free on soundcard
3182006-09-16 03:35:57.884 AO: 62784 bytes free on soundcard
3192006-09-16 03:35:57.892 AO: 60224 bytes free on soundcard
3202006-09-16 03:35:57.900 AO: 61792 bytes free on soundcard
3212006-09-16 03:35:57.908 AO: 59200 bytes free on soundcard
3222006-09-16 03:35:57.916 AO: 60736 bytes free on soundcard
3232006-09-16 03:35:57.924 AO: 62304 bytes free on soundcard
3242006-09-16 03:35:57.932 AO: 59712 bytes free on soundcard
3252006-09-16 03:35:57.940 AO: 61248 bytes free on soundcard
3262006-09-16 03:35:57.948 AO: 62816 bytes free on soundcard
3272006-09-16 03:35:57.956 AO: 60224 bytes free on soundcard
3282006-09-16 03:35:57.964 AO: 61760 bytes free on soundcard
3292006-09-16 03:35:57.973 AO: 59232 bytes free on soundcard
3302006-09-16 03:35:57.980 AO: 60736 bytes free on soundcard
3312006-09-16 03:35:57.988 AO: 62272 bytes free on soundcard
3322006-09-16 03:35:57.997 AO: 59776 bytes free on soundcard
3332006-09-16 03:35:58.004 AO: 61264 bytes free on soundcard
3342006-09-16 03:35:58.012 AO: 62816 bytes free on soundcard
3352006-09-16 03:35:58.020 AO: 60256 bytes free on soundcard
3362006-09-16 03:35:58.033 AO: 62624 bytes free on soundcard
3372006-09-16 03:35:58.044 AO: 60736 bytes free on soundcard
3382006-09-16 03:35:58.052 AO: 62304 bytes free on soundcard
3392006-09-16 03:35:58.064 AO: 60512 bytes free on soundcard
3402006-09-16 03:35:58.072 AO: 62048 bytes free on soundcard
3412006-09-16 03:35:58.081 AO: 59552 bytes free on soundcard
3422006-09-16 03:35:58.083 OSD: InitCC708() -- end
3432006-09-16 03:35:58.089 AO: 61120 bytes free on soundcard
3442006-09-16 03:35:58.099 AO: 63008 bytes free on soundcard
3452006-09-16 03:35:58.105 AO: 60032 bytes free on soundcard
3462006-09-16 03:35:58.113 AO: 61600 bytes free on soundcard
3472006-09-16 03:35:58.123 AO: 59392 bytes free on soundcard
3482006-09-16 03:35:58.130 AO: 60736 bytes free on soundcard
3492006-09-16 03:35:58.138 AO: 62304 bytes free on soundcard
3502006-09-16 03:35:58.146 AO: 59776 bytes free on soundcard
3512006-09-16 03:35:58.152 AO: 61024 bytes free on soundcard
3522006-09-16 03:35:58.161 AO: 62656 bytes free on soundcard
3532006-09-16 03:35:58.169 AO: 60032 bytes free on soundcard
3542006-09-16 03:35:58.177 AO: 61600 bytes free on soundcard
3552006-09-16 03:35:58.184 AO: 58976 bytes free on soundcard
3562006-09-16 03:35:58.193 AO: 60608 bytes free on soundcard
3572006-09-16 03:35:58.202 AO: 62336 bytes free on soundcard
3582006-09-16 03:35:58.209 AO: 59552 bytes free on soundcard
3592006-09-16 03:35:58.218 AO: 61344 bytes free on soundcard
3602006-09-16 03:35:58.225 AO: 62656 bytes free on soundcard
3612006-09-16 03:35:58.236 AO: 60672 bytes free on soundcard
3622006-09-16 03:35:58.244 AO: 62304 bytes free on soundcard
3632006-09-16 03:35:58.255 AO: 60288 bytes free on soundcard
3642006-09-16 03:35:58.260 AO: 61280 bytes free on soundcard
3652006-09-16 03:35:58.270 AO: 63072 bytes free on soundcard
3662006-09-16 03:35:58.277 AO: 60320 bytes free on soundcard
3672006-09-16 03:35:58.290 AO: 62208 bytes free on soundcard
3682006-09-16 03:35:58.296 AO: 60000 bytes free on soundcard
3692006-09-16 03:35:58.307 AO: 62112 bytes free on soundcard
3702006-09-16 03:35:58.314 AO: 59328 bytes free on soundcard
3712006-09-16 03:35:58.322 AO: 60896 bytes free on soundcard
3722006-09-16 03:35:58.328 AO: 62048 bytes free on soundcard
3732006-09-16 03:35:58.337 AO: 59584 bytes free on soundcard
3742006-09-16 03:35:58.344 AO: 61024 bytes free on soundcard
3752006-09-16 03:35:58.353 AO: 62624 bytes free on soundcard
3762006-09-16 03:35:58.361 AO: 60032 bytes free on soundcard
3772006-09-16 03:35:58.369 AO: 61664 bytes free on soundcard
3782006-09-16 03:35:58.376 AO: 58976 bytes free on soundcard
3792006-09-16 03:35:58.385 AO: 60640 bytes free on soundcard
3802006-09-16 03:35:58.392 AO: 62048 bytes free on soundcard
3812006-09-16 03:35:58.402 AO: 59808 bytes free on soundcard
3822006-09-16 03:35:58.409 AO: 61056 bytes free on soundcard
3832006-09-16 03:35:58.418 AO: 62912 bytes free on soundcard
3842006-09-16 03:35:58.424 AO: 60000 bytes free on soundcard
3852006-09-16 03:35:58.438 AO: 62528 bytes free on soundcard
3862006-09-16 03:35:58.445 AO: 59808 bytes free on soundcard
3872006-09-16 03:35:58.454 AO: 61664 bytes free on soundcard
3882006-09-16 03:35:58.461 AO: 58752 bytes free on soundcard
3892006-09-16 03:35:58.470 AO: 60640 bytes free on soundcard
3902006-09-16 03:35:58.476 AO: 61792 bytes free on soundcard
3912006-09-16 03:35:58.485 AO: 59328 bytes free on soundcard
3922006-09-16 03:35:58.492 AO: 60768 bytes free on soundcard
3932006-09-16 03:35:58.501 AO: 62368 bytes free on soundcard
3942006-09-16 03:35:58.509 AO: 59808 bytes free on soundcard
3952006-09-16 03:35:58.517 AO: 61344 bytes free on soundcard
3962006-09-16 03:35:58.525 AO: 62848 bytes free on soundcard
3972006-09-16 03:35:58.533 AO: 60352 bytes free on soundcard
3982006-09-16 03:35:58.540 AO: 61792 bytes free on soundcard
3992006-09-16 03:35:58.549 AO: 59296 bytes free on soundcard
4002006-09-16 03:35:58.557 AO: 60800 bytes free on soundcard
4012006-09-16 03:35:58.565 AO: 62400 bytes free on soundcard
4022006-09-16 03:35:58.573 AO: 59776 bytes free on soundcard
4032006-09-16 03:35:58.581 AO: 61376 bytes free on soundcard
4042006-09-16 03:35:58.588 AO: 62848 bytes free on soundcard
4052006-09-16 03:35:58.597 AO: 60288 bytes free on soundcard
4062006-09-16 03:35:58.605 AO: 61824 bytes free on soundcard
4072006-09-16 03:35:58.615 AO: 59744 bytes free on soundcard
4082006-09-16 03:35:58.620 AO: 60800 bytes free on soundcard
4092006-09-16 03:35:58.631 AO: 62816 bytes free on soundcard
4102006-09-16 03:35:58.637 AO: 59840 bytes free on soundcard
4112006-09-16 03:35:58.647 AO: 61792 bytes free on soundcard
4122006-09-16 03:35:58.653 AO: 58816 bytes free on soundcard
4132006-09-16 03:35:58.663 AO: 60800 bytes free on soundcard
4142006-09-16 03:35:58.668 AO: 61792 bytes free on soundcard
4152006-09-16 03:35:58.679 AO: 59680 bytes free on soundcard
4162006-09-16 03:35:58.685 AO: 60832 bytes free on soundcard
4172006-09-16 03:35:58.692 AO: 62336 bytes free on soundcard
4182006-09-16 03:35:58.701 AO: 59840 bytes free on soundcard
4192006-09-16 03:35:58.708 AO: 61312 bytes free on soundcard
4202006-09-16 03:35:58.717 AO: 62880 bytes free on soundcard
4212006-09-16 03:35:58.725 AO: 60352 bytes free on soundcard
4222006-09-16 03:35:58.735 AO: 62400 bytes free on soundcard
4232006-09-16 03:35:58.740 AO: 59264 bytes free on soundcard
4242006-09-16 03:35:58.752 AO: 61408 bytes free on soundcard
4252006-09-16 03:35:58.756 AO: 62336 bytes free on soundcard
4262006-09-16 03:35:58.768 AO: 60416 bytes free on soundcard
4272006-09-16 03:35:58.773 AO: 61376 bytes free on soundcard
4282006-09-16 03:35:58.783 AO: 63392 bytes free on soundcard
4292006-09-16 03:35:58.788 AO: 60288 bytes free on soundcard
4302006-09-16 03:35:58.799 AO: 62400 bytes free on soundcard
4312006-09-16 03:35:58.804 AO: 59264 bytes free on soundcard
4322006-09-16 03:35:58.813 AO: 60864 bytes free on soundcard
4332006-09-16 03:35:58.821 AO: 62400 bytes free on soundcard
4342006-09-16 03:35:58.829 AO: 59840 bytes free on soundcard
4352006-09-16 03:35:58.837 AO: 61408 bytes free on soundcard
4362006-09-16 03:35:58.845 AO: 63040 bytes free on soundcard
4372006-09-16 03:35:58.856 AO: 60960 bytes free on soundcard
4382006-09-16 03:35:58.861 AO: 61888 bytes free on soundcard
4392006-09-16 03:35:58.872 AO: 59904 bytes free on soundcard
4402006-09-16 03:35:58.877 AO: 60864 bytes free on soundcard
4412006-09-16 03:35:58.889 AO: 63232 bytes free on soundcard
4422006-09-16 03:35:58.896 AO: 60544 bytes free on soundcard
4432006-09-16 03:35:58.905 AO: 62240 bytes free on soundcard
4442006-09-16 03:35:58.912 AO: 59520 bytes free on soundcard
4452006-09-16 03:35:58.920 AO: 61056 bytes free on soundcard
4462006-09-16 03:35:58.929 AO: 62624 bytes free on soundcard
4472006-09-16 03:35:58.936 AO: 60032 bytes free on soundcard
4482006-09-16 03:35:58.944 AO: 61568 bytes free on soundcard
4492006-09-16 03:35:58.953 AO: 59040 bytes free on soundcard
4502006-09-16 03:35:58.960 AO: 60544 bytes free on soundcard
4512006-09-16 03:35:58.971 AO: 62592 bytes free on soundcard
4522006-09-16 03:35:58.977 AO: 59552 bytes free on soundcard
4532006-09-16 03:35:58.986 AO: 61440 bytes free on soundcard
4542006-09-16 03:35:58.994 AO: 58848 bytes free on soundcard
4552006-09-16 03:35:59.004 AO: 60736 bytes free on soundcard
4562006-09-16 03:35:59.013 AO: 62368 bytes free on soundcard
4572006-09-16 03:35:59.021 AO: 59872 bytes free on soundcard
4582006-09-16 03:35:59.028 AO: 61312 bytes free on soundcard
4592006-09-16 03:35:59.037 AO: 62976 bytes free on soundcard
4602006-09-16 03:35:59.044 AO: 60288 bytes free on soundcard
4612006-09-16 03:35:59.055 AO: 62336 bytes free on soundcard
4622006-09-16 03:35:59.061 AO: 59296 bytes free on soundcard
4632006-09-16 03:35:59.069 AO: 60864 bytes free on soundcard
4642006-09-16 03:35:59.078 AO: 62560 bytes free on soundcard
4652006-09-16 03:35:59.085 AO: 59840 bytes free on soundcard
4662006-09-16 03:35:59.092 AO: 61312 bytes free on soundcard
4672006-09-16 03:35:59.101 AO: 62912 bytes free on soundcard
4682006-09-16 03:35:59.108 AO: 60288 bytes free on soundcard
4692006-09-16 03:35:59.117 AO: 61888 bytes free on soundcard
4702006-09-16 03:35:59.125 AO: 59424 bytes free on soundcard
4712006-09-16 03:35:59.133 AO: 60864 bytes free on soundcard
4722006-09-16 03:35:59.141 AO: 62496 bytes free on soundcard
4732006-09-16 03:35:59.149 AO: 59872 bytes free on soundcard
4742006-09-16 03:35:59.157 AO: 61344 bytes free on soundcard
4752006-09-16 03:35:59.165 AO: 62912 bytes free on soundcard
4762006-09-16 03:35:59.173 AO: 60320 bytes free on soundcard
4772006-09-16 03:35:59.181 AO: 61920 bytes free on soundcard
4782006-09-16 03:35:59.188 AO: 59264 bytes free on soundcard
4792006-09-16 03:35:59.197 AO: 60896 bytes free on soundcard
4802006-09-16 03:35:59.204 AO: 62336 bytes free on soundcard
4812006-09-16 03:35:59.213 AO: 59840 bytes free on soundcard
4822006-09-16 03:35:59.221 AO: 61344 bytes free on soundcard
4832006-09-16 03:35:59.229 AO: 62912 bytes free on soundcard
4842006-09-16 03:35:59.236 AO: 60288 bytes free on soundcard
4852006-09-16 03:35:59.245 AO: 61920 bytes free on soundcard
4862006-09-16 03:35:59.253 AO: 59296 bytes free on soundcard
4872006-09-16 03:35:59.261 AO: 60864 bytes free on soundcard
4882006-09-16 03:35:59.271 AO: 62752 bytes free on soundcard
4892006-09-16 03:35:59.277 AO: 59872 bytes free on soundcard
4902006-09-16 03:35:59.285 AO: 61344 bytes free on soundcard
4912006-09-16 03:35:59.293 AO: 62944 bytes free on soundcard
4922006-09-16 03:35:59.300 AO: 60320 bytes free on soundcard
4932006-09-16 03:35:59.311 AO: 62400 bytes free on soundcard
4942006-09-16 03:35:59.317 AO: 59296 bytes free on soundcard
4952006-09-16 03:35:59.325 AO: 60864 bytes free on soundcard
4962006-09-16 03:35:59.332 AO: 62368 bytes free on soundcard
4972006-09-16 03:35:59.341 AO: 59872 bytes free on soundcard
4982006-09-16 03:35:59.350 AO: 61600 bytes free on soundcard
4992006-09-16 03:35:59.357 AO: 58816 bytes free on soundcard
5002006-09-16 03:35:59.365 AO: 60320 bytes free on soundcard
5012006-09-16 03:35:59.373 AO: 61920 bytes free on soundcard
5022006-09-16 03:35:59.381 TV: StartPlayer(): took 3206 ms to start player.
5032006-09-16 03:35:59.382 TV: Changing from None to WatchingLiveTV
5042006-09-16 03:35:59.383 AO: 59840 bytes free on soundcard
5052006-09-16 03:35:59.385 write -> 15 35      QUERY_RECORDER 1[]:[]FRONTEND_READY
5062006-09-16 03:35:59.386 read  <- 15 2       ok
5072006-09-16 03:35:59.387 write -> 15 30      QUERY_RECORDER 1[]:[]GET_INPUT
5082006-09-16 03:35:59.388 read  <- 15 10      television
5092006-09-16 03:35:59.389 AO: 60832 bytes free on soundcard
5102006-09-16 03:35:59.398 AO: 62656 bytes free on soundcard
5112006-09-16 03:35:59.376 NVP: ClearAfterSeek(1)
5122006-09-16 03:35:59.399 VideoOutputXv: ClearAfterSeek()
5132006-09-16 03:35:59.400 VideoOutputXv: DiscardFrames(0)
5142006-09-16 03:35:59.400 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAAAA
5152006-09-16 03:35:59.400 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done()
5162006-09-16 03:35:59.401 VideoOutputXv: DiscardFrames(1)
5172006-09-16 03:35:59.401 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAAAA
5182006-09-16 03:35:59.401 VideoOutputXv: DiscardFrames() 2: AAAAAAAAAAAAAAAA
5192006-09-16 03:35:59.402 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAA -- done()
5202006-09-16 03:35:59.405 AO: OutputAudioLoop: audio paused
5212006-09-16 03:35:59.405 AO: 59904 bytes free on soundcard
5222006-09-16 03:35:59.407 The realtime priority setting is not enabled.
5232006-09-16 03:35:59.414 AO: 61632 bytes free on soundcard
5242006-09-16 03:35:59.421 AO: 58816 bytes free on soundcard
5252006-09-16 03:35:59.428 AO: 60288 bytes free on soundcard
5262006-09-16 03:35:59.437 AO: 61888 bytes free on soundcard
5272006-09-16 03:35:59.445 AO: 59296 bytes free on soundcard
5282006-09-16 03:35:59.453 AO: 60864 bytes free on soundcard
5292006-09-16 03:35:59.460 AO: 62336 bytes free on soundcard
5302006-09-16 03:35:59.470 AO: 60160 bytes free on soundcard
5312006-09-16 03:35:59.477 AO: 61344 bytes free on soundcard
5322006-09-16 03:35:59.485 AO: 63008 bytes free on soundcard
5332006-09-16 03:35:59.492 AO: 60288 bytes free on soundcard
5342006-09-16 03:35:59.503 AO: 62272 bytes free on soundcard
5352006-09-16 03:35:59.509 AO: 59296 bytes free on soundcard
5362006-09-16 03:35:59.681 AO: 65536 bytes free on soundcard
5372006-09-16 03:35:59.907 AO: 63360 bytes free on soundcard
5382006-09-16 03:35:59.912 AO: 60416 bytes free on soundcard
5392006-09-16 03:35:59.921 AO: 62016 bytes free on soundcard
5402006-09-16 03:35:59.929 AO: 59456 bytes free on soundcard
5412006-09-16 03:35:59.932 nVidiaVideoSync: Could not open device /dev/nvidia0, No such device or address
5422006-09-16 03:35:59.936 AO: 60928 bytes free on soundcard
5432006-09-16 03:35:59.945 AO: 62496 bytes free on soundcard
5442006-09-16 03:35:59.952 AO: 59904 bytes free on soundcard
5452006-09-16 03:35:59.960 AO: 61440 bytes free on soundcard
5462006-09-16 03:35:59.969 AO: 58912 bytes free on soundcard
5472006-09-16 03:35:59.970 Using audio as timebase
5482006-09-16 03:35:59.971 Video timing method: DRM
5492006-09-16 03:35:59.971 Refresh rate: 20000, frame interval: 40000
5502006-09-16 03:35:59.972 AFD: video timecode 90038 79238 880 612
5512006-09-16 03:35:59.977 AO: 60448 bytes free on soundcard
5522006-09-16 03:35:59.985 AO: 61984 bytes free on soundcard
5532006-09-16 03:35:59.985 NVP: Waiting for prebuffer.. 0 uLAAAAAAAAAAAAAA
5542006-09-16 03:35:59.986 AFD: audio timecode 81010 81010 900 924
5552006-09-16 03:35:59.986 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=900
5562006-09-16 03:35:59.990 AFD: audio timecode 83170 83170 924 948
5572006-09-16 03:35:59.995 AO: 59872 bytes free on soundcard
5582006-09-16 03:35:59.990 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=924
5592006-09-16 03:36:00.000 AO: 60928 bytes free on soundcard
5602006-09-16 03:36:00.000 AFD: audio timecode 85330 85330 948 972
5612006-09-16 03:36:00.001 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=948
5622006-09-16 03:36:00.003 AFD: audio timecode 87490 87490 972 996
5632006-09-16 03:36:00.003 AO: _AddSamples bytes=4608, used=13825, free=754175, timecode=972
5642006-09-16 03:36:00.013 AO: 63360 bytes free on soundcard
5652006-09-16 03:36:00.020 AO: 60672 bytes free on soundcard
5662006-09-16 03:36:00.029 AO: 62336 bytes free on soundcard
5672006-09-16 03:36:00.030 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1
5682006-09-16 03:36:00.036 AO: OutputAudioLoop: Play Event
5692006-09-16 03:36:00.037 AO: Broadcasting free space avail
5702006-09-16 03:36:00.037 AO: Broadcasting free space avail
5712006-09-16 03:36:00.038 AO: Broadcasting free space avail
5722006-09-16 03:36:00.038 AO: Broadcasting free space avail
5732006-09-16 03:36:00.038 AO: audio waiting for buffer to fill: have 2048 want 4096
5742006-09-16 03:36:00.038 AO: Broadcasting free space avail
575X Error: BadAlloc (insufficient resources for operation) 11
576  Major opcode:  141
577  Minor opcode:  18
578  Resource id:  0x2800016
5792006-09-16 03:36:00.042 NVP: prebuffering pause
5802006-09-16 03:36:00.042 NVP: Waiting for prebuffer.. 0 dLLAAAAAAAAAAAAA
5812006-09-16 03:36:00.049 AO: OutputAudioLoop: audio paused
5822006-09-16 03:36:00.050 AO: 45792 bytes free on soundcard
5832006-09-16 03:36:00.056 AO: 47104 bytes free on soundcard
5842006-09-16 03:36:00.066 AO: 49024 bytes free on soundcard
5852006-09-16 03:36:00.068 AFD: video timecode 82838 82838 920 996
5862006-09-16 03:36:00.071 AFD: audio timecode 89650 89650 996 1020
5872006-09-16 03:36:00.071 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=996
5882006-09-16 03:36:00.072 AO: 50176 bytes free on soundcard
5892006-09-16 03:36:00.082 AO: 52096 bytes free on soundcard
5902006-09-16 03:36:00.089 AO: OutputAudioLoop: Play Event
5912006-09-16 03:36:00.089 AO: Broadcasting free space avail
5922006-09-16 03:36:00.089 AO: audio waiting for buffer to fill: have 2560 want 4096
5932006-09-16 03:36:00.089 AO: Broadcasting free space avail
5942006-09-16 03:36:00.088 NVP: A/V Divergence: 0.0375, Rate: 0.0375, Warpfactor: 1.0005, warpfactor_avg: 1
5952006-09-16 03:36:00.102 AO: audio waiting for buffer to fill: have 2560 want 4096
5962006-09-16 03:36:00.103 AO: Broadcasting free space avail
5972006-09-16 03:36:00.106 NVP: prebuffering pause
5982006-09-16 03:36:00.107 NVP: Waiting for prebuffer.. 0 dLDLAAAAAAAAAAAA
5992006-09-16 03:36:00.108 AO: OutputAudioLoop: audio paused
6002006-09-16 03:36:00.109 AO: 53056 bytes free on soundcard
6012006-09-16 03:36:00.117 AO: 54624 bytes free on soundcard
6022006-09-16 03:36:00.117 AFD: video timecode 86438 86438 960 1020
6032006-09-16 03:36:00.119 AFD: audio timecode 91810 91810 1020 1044
6042006-09-16 03:36:00.119 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1020
6052006-09-16 03:36:00.121 AFD: audio timecode 93970 93970 1044 1068
6062006-09-16 03:36:00.121 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=1044
6072006-09-16 03:36:00.125 AO: 56096 bytes free on soundcard
6082006-09-16 03:36:00.134 AO: 57984 bytes free on soundcard
6092006-09-16 03:36:00.137 NVP: A/V Divergence: -0.084375, Rate: -0.121875, Warpfactor: 1, warpfactor_avg: 1
6102006-09-16 03:36:00.139 NVP: prebuffering pause
6112006-09-16 03:36:00.139 NVP: Waiting for prebuffer.. 0 DLADLAAAAAAAAAAA
6122006-09-16 03:36:00.141 AO: OutputAudioLoop: audio paused
6132006-09-16 03:36:00.141 AO: 59264 bytes free on soundcard
6142006-09-16 03:36:00.149 AO: 60768 bytes free on soundcard
6152006-09-16 03:36:00.156 AO: 62208 bytes free on soundcard
6162006-09-16 03:36:00.161 AFD: video timecode 100838 90038 1000 1068
6172006-09-16 03:36:00.167 AO: 60224 bytes free on soundcard
6182006-09-16 03:36:00.171 AFD: audio timecode 96130 96130 1068 1092
6192006-09-16 03:36:00.171 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=1068
6202006-09-16 03:36:00.173 AO: 61216 bytes free on soundcard
6212006-09-16 03:36:00.173 AFD: audio timecode 98290 98290 1092 1116
6222006-09-16 03:36:00.174 AO: _AddSamples bytes=4608, used=16385, free=751615, timecode=1092
6232006-09-16 03:36:00.186 AO: 63744 bytes free on soundcard
6242006-09-16 03:36:00.190 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1
6252006-09-16 03:36:00.193 AO: OutputAudioLoop: Play Event
6262006-09-16 03:36:00.193 AO: Broadcasting free space avail
6272006-09-16 03:36:00.193 AO: Broadcasting free space avail
6282006-09-16 03:36:00.193 AO: Broadcasting free space avail
6292006-09-16 03:36:00.194 AO: Broadcasting free space avail
6302006-09-16 03:36:00.194 AO: Broadcasting free space avail
6312006-09-16 03:36:00.194 AO: audio waiting for buffer to fill: have 512 want 4096
6322006-09-16 03:36:00.194 AO: Broadcasting free space avail
6332006-09-16 03:36:00.203 AO: audio waiting for buffer to fill: have 512 want 4096
6342006-09-16 03:36:00.203 AO: Broadcasting free space avail
6352006-09-16 03:36:00.205 NVP: prebuffering pause
6362006-09-16 03:36:00.206 NVP: Waiting for prebuffer.. 0 AdAALLAAAAAAAAAA
6372006-09-16 03:36:00.209 AO: OutputAudioLoop: audio paused
6382006-09-16 03:36:00.209 AO: 43616 bytes free on soundcard
6392006-09-16 03:36:00.213 AFD: video timecode 93638 93638 1040 1116
6402006-09-16 03:36:00.218 AO: 45280 bytes free on soundcard
6412006-09-16 03:36:00.218 AFD: audio timecode 100450 100450 1116 1140
6422006-09-16 03:36:00.219 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1116
6432006-09-16 03:36:00.225 AO: 46656 bytes free on soundcard
6442006-09-16 03:36:00.229 NVP: A/V Divergence: 0.025, Rate: 0.025, Warpfactor: 1.0004, warpfactor_avg: 1
6452006-09-16 03:36:00.233 AO: OutputAudioLoop: Play Event
6462006-09-16 03:36:00.233 AO: Broadcasting free space avail
6472006-09-16 03:36:00.234 AO: audio waiting for buffer to fill: have 1024 want 4096
6482006-09-16 03:36:00.234 AO: Broadcasting free space avail
6492006-09-16 03:36:00.240 AO: audio waiting for buffer to fill: have 1024 want 4096
6502006-09-16 03:36:00.241 AO: Broadcasting free space avail
6512006-09-16 03:36:00.245 NVP: prebuffering pause
6522006-09-16 03:36:00.245 NVP: Waiting for prebuffer.. 0 AdAALDLAAAAAAAAA
6532006-09-16 03:36:00.246 AFD: video timecode 97238 97238 1080 1140
6542006-09-16 03:36:00.249 AO: OutputAudioLoop: audio paused
6552006-09-16 03:36:00.249 AO: 47264 bytes free on soundcard
6562006-09-16 03:36:00.248 AFD: audio timecode 102610 102610 1140 1164
6572006-09-16 03:36:00.251 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1140
6582006-09-16 03:36:00.253 AFD: audio timecode 104770 104770 1164 1188
6592006-09-16 03:36:00.253 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=1164
6602006-09-16 03:36:00.257 AO: 48704 bytes free on soundcard
6612006-09-16 03:36:00.265 AO: 50336 bytes free on soundcard
6622006-09-16 03:36:00.269 NVP: A/V Divergence: 0.05, Rate: 0.025, Warpfactor: 1.0006, warpfactor_avg: 1
6632006-09-16 03:36:00.272 AO: OutputAudioLoop: Play Event
6642006-09-16 03:36:00.273 AO: Broadcasting free space avail
6652006-09-16 03:36:00.273 AO: Broadcasting free space avail
6662006-09-16 03:36:00.273 AO: audio waiting for buffer to fill: have 2048 want 4096
6672006-09-16 03:36:00.274 AO: Broadcasting free space avail
6682006-09-16 03:36:00.285 AO: audio waiting for buffer to fill: have 2048 want 4096
6692006-09-16 03:36:00.285 AO: Broadcasting free space avail
6702006-09-16 03:36:00.286 NVP: prebuffering pause
6712006-09-16 03:36:00.287 NVP: Waiting for prebuffer.. 0 ADAALADLAAAAAAAA
6722006-09-16 03:36:00.287 AFD: video timecode 111638 100838 1120 1188
6732006-09-16 03:36:00.289 AFD: audio timecode 106930 106930 1188 1212
6742006-09-16 03:36:00.289 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1188
6752006-09-16 03:36:00.292 AFD: audio timecode 109090 109090 1212 1236
6762006-09-16 03:36:00.293 AO: OutputAudioLoop: audio paused
6772006-09-16 03:36:00.293 AO: 47488 bytes free on soundcard
6782006-09-16 03:36:00.292 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=1212
6792006-09-16 03:36:00.301 AO: 49024 bytes free on soundcard
6802006-09-16 03:36:00.308 NVP: A/V Divergence: 0.05625, Rate: 0.00625, Warpfactor: 1.0005, warpfactor_avg: 1
6812006-09-16 03:36:00.310 AO: OutputAudioLoop: Play Event
6822006-09-16 03:36:00.311 AO: Broadcasting free space avail
6832006-09-16 03:36:00.311 AO: Broadcasting free space avail
6842006-09-16 03:36:00.311 AO: audio waiting for buffer to fill: have 3072 want 4096
6852006-09-16 03:36:00.312 AO: Broadcasting free space avail
6862006-09-16 03:36:00.318 AO: audio waiting for buffer to fill: have 3072 want 4096
6872006-09-16 03:36:00.319 AO: Broadcasting free space avail
6882006-09-16 03:36:00.325 AO: audio waiting for buffer to fill: have 3072 want 4096
6892006-09-16 03:36:00.325 AO: Broadcasting free space avail
6902006-09-16 03:36:00.327 NVP: prebuffering pause
6912006-09-16 03:36:00.327 NVP: Waiting for prebuffer.. 0 AAAAdAALLAAAAAAA
6922006-09-16 03:36:00.334 AO: OutputAudioLoop: audio paused
6932006-09-16 03:36:00.334 AO: 47200 bytes free on soundcard
6942006-09-16 03:36:00.335 AFD: video timecode 104438 104438 1160 1236
6952006-09-16 03:36:00.337 AFD: audio timecode 111250 111250 1236 1260
6962006-09-16 03:36:00.338 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1236
6972006-09-16 03:36:00.340 AO: 48416 bytes free on soundcard
6982006-09-16 03:36:00.348 NVP: A/V Divergence: 0.067175, Rate: 0.010925, Warpfactor: 1.00062, warpfactor_avg: 1
6992006-09-16 03:36:00.352 AO: OutputAudioLoop: Play Event
7002006-09-16 03:36:00.353 AO: Broadcasting free space avail
7012006-09-16 03:36:00.353 AO: audio waiting for buffer to fill: have 3584 want 4096
7022006-09-16 03:36:00.353 AO: Broadcasting free space avail
7032006-09-16 03:36:00.361 AO: audio waiting for buffer to fill: have 3584 want 4096
7042006-09-16 03:36:00.361 AO: Broadcasting free space avail
7052006-09-16 03:36:00.370 NVP: prebuffering pause
7062006-09-16 03:36:00.371 NVP: Waiting for prebuffer.. 0 AAAAdAALDLAAAAAA
7072006-09-16 03:36:00.369 AO: audio waiting for buffer to fill: have 3584 want 4096
7082006-09-16 03:36:00.371 AO: Broadcasting free space avail
7092006-09-16 03:36:00.377 AO: OutputAudioLoop: audio paused
7102006-09-16 03:36:00.377 AO: 51360 bytes free on soundcard
7112006-09-16 03:36:00.387 AO: 53216 bytes free on soundcard
7122006-09-16 03:36:00.389 AFD: video timecode 108038 108038 1200 1260
7132006-09-16 03:36:00.390 AFD: audio timecode 113410 113410 1260 1284
7142006-09-16 03:36:00.391 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=1260
7152006-09-16 03:36:00.393 AO: 54368 bytes free on soundcard
7162006-09-16 03:36:00.396 AFD: audio timecode 115570 115570 1284 1308
7172006-09-16 03:36:00.398 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=1284
7182006-09-16 03:36:00.398 AFD: HandleGopStart: gopset not set, syncing positionMap
7192006-09-16 03:36:00.398 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
7202006-09-16 03:36:00.401 AO: 55840 bytes free on soundcard
7212006-09-16 03:36:00.410 NVP: A/V Divergence: 0.044125, Rate: -0.02305, Warpfactor: 1.00017, warpfactor_avg: 1
7222006-09-16 03:36:00.411 AO: OutputAudioLoop: Play Event
7232006-09-16 03:36:00.411 AO: Broadcasting free space avail
7242006-09-16 03:36:00.412 AO: Broadcasting free space avail
7252006-09-16 03:36:00.412 AO: Broadcasting free space avail
7262006-09-16 03:36:00.412 AO: audio waiting for buffer to fill: have 512 want 4096
7272006-09-16 03:36:00.413 AO: Broadcasting free space avail
7282006-09-16 03:36:00.413 Position map filled from DB to: 6
7292006-09-16 03:36:00.414 SyncPositionMap watchingrecording, from DB: 6 entries
7302006-09-16 03:36:00.414 write -> 15 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
7312006-09-16 03:36:00.415 read  <- 15 9       0[]:[]108
7322006-09-16 03:36:00.416 Filling position map from 7 to 9
7332006-09-16 03:36:00.416 write -> 15 50      QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]7[]:[]9
7342006-09-16 03:36:00.417 read  <- 15 85      0[]:[]7[]:[]0[]:[]2324480[]:[]0[]:[]8[]:[]0[]:[]2713600[]:[]0[]:[...
7352006-09-16 03:36:00.418 Position map filled from Encoder to: 9
7362006-09-16 03:36:00.418 SyncPositionMap watchingrecording total: 9 entries
7372006-09-16 03:36:00.423 AO: audio waiting for buffer to fill: have 512 want 4096
7382006-09-16 03:36:00.423 AO: Broadcasting free space avail
7392006-09-16 03:36:00.428 NVP: prebuffering pause
7402006-09-16 03:36:00.428 NVP: Waiting for prebuffer.. 0 AAAAdAALAdAAAAAA
7412006-09-16 03:36:00.429 AO: OutputAudioLoop: audio paused
7422006-09-16 03:36:00.429 AO: 49120 bytes free on soundcard
7432006-09-16 03:36:00.418 SyncPositionMap, new totframes: 108, new length: 4, posMap size: 9
7442006-09-16 03:36:00.430 AFD: HandleGopStart: Initial key frame distance: 12.
7452006-09-16 03:36:00.438 AO: 50784 bytes free on soundcard
7462006-09-16 03:36:00.445 AO: 52064 bytes free on soundcard
7472006-09-16 03:36:00.456 AO: 54272 bytes free on soundcard
7482006-09-16 03:36:00.465 AO: 55840 bytes free on soundcard
7492006-09-16 03:36:00.466 AFD: video timecode 122438 111638 1240 1308
7502006-09-16 03:36:00.468 AFD: audio timecode 117730 117730 1308 1332
7512006-09-16 03:36:00.468 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1308
7522006-09-16 03:36:00.470 AFD: audio timecode 119890 119890 1332 1356
7532006-09-16 03:36:00.470 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=1332
7542006-09-16 03:36:00.473 AO: 57408 bytes free on soundcard
7552006-09-16 03:36:00.481 AO: 58944 bytes free on soundcard
7562006-09-16 03:36:00.488 NVP: A/V Divergence: -0.0794, Rate: -0.123525, Warpfactor: 0.999668, warpfactor_avg: 1
7572006-09-16 03:36:00.489 AO: OutputAudioLoop: Play Event
7582006-09-16 03:36:00.489 AO: Broadcasting free space avail
7592006-09-16 03:36:00.490 AO: Broadcasting free space avail
7602006-09-16 03:36:00.490 AO: audio waiting for buffer to fill: have 1536 want 4096
7612006-09-16 03:36:00.490 AO: Broadcasting free space avail
7622006-09-16 03:36:00.496 AO: audio waiting for buffer to fill: have 1536 want 4096
7632006-09-16 03:36:00.497 AO: Broadcasting free space avail
7642006-09-16 03:36:00.501 AFD: video timecode 115238 115238 1280 1356
7652006-09-16 03:36:00.505 AO: audio waiting for buffer to fill: have 1536 want 4096
7662006-09-16 03:36:00.505 AO: Broadcasting free space avail
7672006-09-16 03:36:00.516 NVP: A/V Divergence: -0.4533, Rate: -0.3739, Warpfactor: 0.999169, warpfactor_avg: 1
7682006-09-16 03:36:00.517 AO: audio waiting for buffer to fill: have 1536 want 4096
7692006-09-16 03:36:00.517 AO: Broadcasting free space avail
7702006-09-16 03:36:00.504 AFD: audio timecode 122050 122050 1356 1380
7712006-09-16 03:36:00.518 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1356
7722006-09-16 03:36:00.530 AO: Broadcasting free space avail
7732006-09-16 03:36:00.530 AO: audio waiting for buffer to fill: have 2048 want 4096
7742006-09-16 03:36:00.531 AO: Broadcasting free space avail
7752006-09-16 03:36:00.532 NVP: prebuffering pause
7762006-09-16 03:36:00.532 NVP: Waiting for prebuffer.. 0 AAAAAAAdAALDLAAA
7772006-09-16 03:36:00.537 AO: OutputAudioLoop: audio paused
7782006-09-16 03:36:00.537 AO: 57472 bytes free on soundcard
7792006-09-16 03:36:00.548 AO: 59488 bytes free on soundcard
7802006-09-16 03:36:00.553 AO: 60608 bytes free on soundcard
7812006-09-16 03:36:00.561 AO: 62112 bytes free on soundcard
7822006-09-16 03:36:00.569 AO: 59456 bytes free on soundcard
7832006-09-16 03:36:00.577 AO: 61088 bytes free on soundcard
7842006-09-16 03:36:00.585 AO: 62528 bytes free on soundcard
7852006-09-16 03:36:00.586 AFD: video timecode 118838 118838 1320 1380
7862006-09-16 03:36:00.588 AFD: audio timecode 124210 124210 1380 1404
7872006-09-16 03:36:00.588 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1380
7882006-09-16 03:36:00.590 AFD: audio timecode 126370 126370 1404 1428
7892006-09-16 03:36:00.590 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=1404
7902006-09-16 03:36:00.595 AO: 60384 bytes free on soundcard
7912006-09-16 03:36:00.601 AO: 61504 bytes free on soundcard
7922006-09-16 03:36:00.608 NVP: A/V Divergence: -0.602475, Rate: -0.149175, Warpfactor: 0.998669, warpfactor_avg: 1
7932006-09-16 03:36:00.612 AO: OutputAudioLoop: Play Event
7942006-09-16 03:36:00.612 AO: Broadcasting free space avail
7952006-09-16 03:36:00.613 AO: Broadcasting free space avail
7962006-09-16 03:36:00.613 AO: audio waiting for buffer to fill: have 3072 want 4096
7972006-09-16 03:36:00.613 AO: Broadcasting free space avail
7982006-09-16 03:36:00.621 AO: audio waiting for buffer to fill: have 3072 want 4096
7992006-09-16 03:36:00.621 AO: Broadcasting free space avail
8002006-09-16 03:36:00.630 AO: audio waiting for buffer to fill: have 3072 want 4096
8012006-09-16 03:36:00.630 AO: Broadcasting free space avail
8022006-09-16 03:36:00.632 NVP: prebuffering pause
8032006-09-16 03:36:00.632 NVP: Waiting for prebuffer.. 0 AAAAAAAAAALADLAA
8042006-09-16 03:36:00.633 AFD: video timecode 133238 122438 1360 1428
8052006-09-16 03:36:00.636 AFD: audio timecode 128530 128530 1428 1452
8062006-09-16 03:36:00.636 AO: OutputAudioLoop: audio paused
8072006-09-16 03:36:00.637 AO: 56160 bytes free on soundcard
8082006-09-16 03:36:00.636 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1428
8092006-09-16 03:36:00.638 AFD: audio timecode 130690 130690 1452 1476
8102006-09-16 03:36:00.639 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1452
8112006-09-16 03:36:00.649 AO: 58464 bytes free on soundcard
8122006-09-16 03:36:00.653 NVP: A/V Divergence: -0.6831, Rate: -0.080625, Warpfactor: 0.99817, warpfactor_avg: 1
8132006-09-16 03:36:00.657 AO: OutputAudioLoop: Play Event
8142006-09-16 03:36:00.658 AO: Broadcasting free space avail
8152006-09-16 03:36:00.658 AO: Broadcasting free space avail
8162006-09-16 03:36:00.658 AO: Broadcasting free space avail
8172006-09-16 03:36:00.658 AO: Broadcasting free space avail
8182006-09-16 03:36:00.667 AO: Broadcasting free space avail
8192006-09-16 03:36:00.668 NVP: prebuffering pause
8202006-09-16 03:36:00.669 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAdAALLA
8212006-09-16 03:36:00.673 AO: OutputAudioLoop: audio paused
8222006-09-16 03:36:00.673 AO: 50880 bytes free on soundcard
8232006-09-16 03:36:00.683 AO: 52704 bytes free on soundcard
8242006-09-16 03:36:00.685 AFD: video timecode 126038 126038 1400 1476
8252006-09-16 03:36:00.686 AFD: audio timecode 132850 132850 1476 1500
8262006-09-16 03:36:00.687 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1476
8272006-09-16 03:36:00.689 AO: 53920 bytes free on soundcard
8282006-09-16 03:36:00.701 AO: 56192 bytes free on soundcard
8292006-09-16 03:36:00.708 NVP: A/V Divergence: -0.731075, Rate: -0.047975, Warpfactor: 0.997671, warpfactor_avg: 1
8302006-09-16 03:36:00.709 AO: OutputAudioLoop: Play Event
8312006-09-16 03:36:00.709 AO: Broadcasting free space avail
8322006-09-16 03:36:00.710 AO: audio waiting for buffer to fill: have 512 want 4096
8332006-09-16 03:36:00.710 AO: Broadcasting free space avail
8342006-09-16 03:36:00.718 AO: audio waiting for buffer to fill: have 512 want 4096
8352006-09-16 03:36:00.719 AO: Broadcasting free space avail
8362006-09-16 03:36:00.725 AO: audio waiting for buffer to fill: have 512 want 4096
8372006-09-16 03:36:00.725 AO: Broadcasting free space avail
8382006-09-16 03:36:00.729 NVP: prebuffering pause
8392006-09-16 03:36:00.730 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAdAALDL
8402006-09-16 03:36:00.730 AFD: video timecode 129638 129638 1440 1500
8412006-09-16 03:36:00.736 AO: OutputAudioLoop: audio paused
8422006-09-16 03:36:00.740 AO: 59616 bytes free on soundcard
8432006-09-16 03:36:00.744 AFD: audio timecode 135009 135009 1500 1524
8442006-09-16 03:36:00.745 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=1500
8452006-09-16 03:36:00.747 AFD: audio timecode 137169 137169 1524 1548
8462006-09-16 03:36:00.747 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=1524
8472006-09-16 03:36:00.752 AO: 61920 bytes free on soundcard
8482006-09-16 03:36:00.761 AO: 59424 bytes free on soundcard
8492006-09-16 03:36:00.768 NVP: A/V Divergence: -0.89205, Rate: -0.160975, Warpfactor: 0.997172, warpfactor_avg: 0.999999
8502006-09-16 03:36:00.769 AO: OutputAudioLoop: Play Event
8512006-09-16 03:36:00.769 AO: Broadcasting free space avail
8522006-09-16 03:36:00.769 AO: Broadcasting free space avail
8532006-09-16 03:36:00.769 AO: audio waiting for buffer to fill: have 1536 want 4096
8542006-09-16 03:36:00.770 AO: Broadcasting free space avail
8552006-09-16 03:36:00.777 AO: audio waiting for buffer to fill: have 1536 want 4096
8562006-09-16 03:36:00.777 AO: Broadcasting free space avail
8572006-09-16 03:36:00.785 AO: audio waiting for buffer to fill: have 1536 want 4096
8582006-09-16 03:36:00.785 AO: Broadcasting free space avail
8592006-09-16 03:36:00.787 NVP: prebuffering pause
8602006-09-16 03:36:00.787 NVP: Waiting for prebuffer.. 0 AALAAAAAAADAALAD
8612006-09-16 03:36:00.788 AFD: video timecode 144038 133238 1480 1548
8622006-09-16 03:36:00.790 AFD: audio timecode 139329 139329 1548 1572
8632006-09-16 03:36:00.790 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1548
8642006-09-16 03:36:00.793 AO: OutputAudioLoop: audio paused
8652006-09-16 03:36:00.793 AO: 57472 bytes free on soundcard
8662006-09-16 03:36:00.796 AFD: audio timecode 141489 141489 1572 1596
8672006-09-16 03:36:00.797 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=1572
8682006-09-16 03:36:00.801 AO: 58944 bytes free on soundcard
8692006-09-16 03:36:00.810 AO: 60800 bytes free on soundcard
8702006-09-16 03:36:00.815 NVP: A/V Divergence: -0.994025, Rate: -0.101975, Warpfactor: 0.996673, warpfactor_avg: 0.999999
8712006-09-16 03:36:00.817 AO: OutputAudioLoop: Play Event
8722006-09-16 03:36:00.817 AO: Broadcasting free space avail
8732006-09-16 03:36:00.817 AO: Broadcasting free space avail
8742006-09-16 03:36:00.817 AO: audio waiting for buffer to fill: have 2560 want 4096
8752006-09-16 03:36:00.818 AO: Broadcasting free space avail
8762006-09-16 03:36:00.826 AO: audio waiting for buffer to fill: have 2560 want 4096
8772006-09-16 03:36:00.826 AO: Broadcasting free space avail
8782006-09-16 03:36:00.827 NVP: prebuffering pause
8792006-09-16 03:36:00.827 NVP: Waiting for prebuffer.. 0 AALLAAAAAAAAAdAA
8802006-09-16 03:36:00.832 AO: OutputAudioLoop: audio paused
8812006-09-16 03:36:00.833 AO: 56928 bytes free on soundcard
8822006-09-16 03:36:00.837 AFD: video timecode 136838 136838 1520 1596
8832006-09-16 03:36:00.841 AO: 58496 bytes free on soundcard
8842006-09-16 03:36:00.842 AFD: audio timecode 143649 143649 1596 1620
8852006-09-16 03:36:00.843 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1596
8862006-09-16 03:36:00.849 AO: 60000 bytes free on soundcard
8872006-09-16 03:36:00.850 NVP: A/V Divergence: -1.0705, Rate: -0.076475, Warpfactor: 0.996175, warpfactor_avg: 0.999999
8882006-09-16 03:36:00.857 AO: OutputAudioLoop: Play Event
8892006-09-16 03:36:00.857 AO: Broadcasting free space avail
8902006-09-16 03:36:00.858 AO: audio waiting for buffer to fill: have 3072 want 4096
8912006-09-16 03:36:00.858 AO: Broadcasting free space avail
8922006-09-16 03:36:00.865 AO: audio waiting for buffer to fill: have 3072 want 4096
8932006-09-16 03:36:00.865 AO: Broadcasting free space avail
8942006-09-16 03:36:00.866 NVP: prebuffering pause
8952006-09-16 03:36:00.867 NVP: Waiting for prebuffer.. 0 LALDAAAAAAAAAdAA
8962006-09-16 03:36:00.878 AO: OutputAudioLoop: audio paused
8972006-09-16 03:36:00.878 AO: 61536 bytes free on soundcard
8982006-09-16 03:36:00.880 AFD: video timecode 140438 140438 1560 1620
8992006-09-16 03:36:00.882 AFD: audio timecode 145809 145809 1620 1644
9002006-09-16 03:36:00.882 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1620
9012006-09-16 03:36:00.885 AO: 58688 bytes free on soundcard
9022006-09-16 03:36:00.887 AFD: audio timecode 147969 147969 1644 1668
9032006-09-16 03:36:00.887 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=1644
9042006-09-16 03:36:00.896 AO: 60928 bytes free on soundcard
9052006-09-16 03:36:00.905 AO: 62560 bytes free on soundcard
9062006-09-16 03:36:00.913 AO: 60032 bytes free on soundcard
9072006-09-16 03:36:00.916 NVP: A/V Divergence: -1.12162, Rate: -0.051125, Warpfactor: 0.995677, warpfactor_avg: 0.999999
9082006-09-16 03:36:00.918 NVP: prebuffering pause
9092006-09-16 03:36:00.918 NVP: Waiting for prebuffer.. 0 DALAALAAAAAAADAA
9102006-09-16 03:36:00.921 AO: OutputAudioLoop: audio paused
9112006-09-16 03:36:00.921 AO: 61568 bytes free on soundcard
9122006-09-16 03:36:00.931 AO: 59424 bytes free on soundcard
9132006-09-16 03:36:00.937 AO: 60480 bytes free on soundcard
9142006-09-16 03:36:00.941 AFD: video timecode 154837 144038 1600 1668
9152006-09-16 03:36:00.945 AO: 62048 bytes free on soundcard
9162006-09-16 03:36:00.947 AFD: audio timecode 150129 150129 1668 1692
9172006-09-16 03:36:00.947 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=1668
9182006-09-16 03:36:00.948 AFD: audio timecode 152289 152289 1692 1716
9192006-09-16 03:36:00.949 AO: _AddSamples bytes=4608, used=16897, free=751103, timecode=1692
9202006-09-16 03:36:00.953 AO: 59552 bytes free on soundcard
9212006-09-16 03:36:00.961 AO: 61088 bytes free on soundcard
9222006-09-16 03:36:00.968 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.996174, warpfactor_avg: 0.999998
9232006-09-16 03:36:00.969 AO: OutputAudioLoop: Play Event
9242006-09-16 03:36:00.969 AO: Broadcasting free space avail
9252006-09-16 03:36:00.970 AO: Broadcasting free space avail
9262006-09-16 03:36:00.970 AO: Broadcasting free space avail
9272006-09-16 03:36:00.970 AO: Broadcasting free space avail
9282006-09-16 03:36:00.971 AO: Broadcasting free space avail
9292006-09-16 03:36:00.971 AO: audio waiting for buffer to fill: have 1024 want 4096
9302006-09-16 03:36:00.971 AO: Broadcasting free space avail
9312006-09-16 03:36:00.978 AO: audio waiting for buffer to fill: have 1024 want 4096
9322006-09-16 03:36:00.978 AO: Broadcasting free space avail
9332006-09-16 03:36:00.981 AFD: video timecode 147638 147638 1640 1716
9342006-09-16 03:36:00.983 AFD: audio timecode 154449 154449 1716 1740
9352006-09-16 03:36:00.984 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1716
9362006-09-16 03:36:00.985 AO: Broadcasting free space avail
9372006-09-16 03:36:00.985 AO: audio waiting for buffer to fill: have 1536 want 4096
9382006-09-16 03:36:00.986 AO: Broadcasting free space avail
9392006-09-16 03:36:00.993 AO: audio waiting for buffer to fill: have 1536 want 4096
9402006-09-16 03:36:00.993 AO: Broadcasting free space avail
9412006-09-16 03:36:00.995 NVP: A/V Divergence: -0.04375, Rate: -0.04375, Warpfactor: 0.996673, warpfactor_avg: 0.999998
9422006-09-16 03:36:01.001 AO: audio waiting for buffer to fill: have 1536 want 4096
9432006-09-16 03:36:01.001 AO: Broadcasting free space avail
9442006-09-16 03:36:01.009 AO: audio waiting for buffer to fill: have 1536 want 4096
9452006-09-16 03:36:01.010 AO: Broadcasting free space avail
9462006-09-16 03:36:01.017 AO: audio waiting for buffer to fill: have 1536 want 4096
9472006-09-16 03:36:01.017 AO: Broadcasting free space avail
9482006-09-16 03:36:01.021 AFD: video timecode 151237 151237 1680 1740
9492006-09-16 03:36:01.023 AFD: audio timecode 156609 156609 1740 1764
9502006-09-16 03:36:01.023 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=1740
9512006-09-16 03:36:01.028 AO: Broadcasting free space avail
9522006-09-16 03:36:01.028 AO: audio waiting for buffer to fill: have 2048 want 4096
9532006-09-16 03:36:01.028 AO: Broadcasting free space avail
9542006-09-16 03:36:01.031 AFD: audio timecode 158769 158769 1764 1788
9552006-09-16 03:36:01.031 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1764
9562006-09-16 03:36:01.033 AO: Broadcasting free space avail
9572006-09-16 03:36:01.033 AO: audio waiting for buffer to fill: have 2560 want 4096
9582006-09-16 03:36:01.033 AO: Broadcasting free space avail
9592006-09-16 03:36:01.043 AO: audio waiting for buffer to fill: have 2560 want 4096
9602006-09-16 03:36:01.044 AO: Broadcasting free space avail
9612006-09-16 03:36:01.049 AO: audio waiting for buffer to fill: have 2560 want 4096
9622006-09-16 03:36:01.049 AO: Broadcasting free space avail
9632006-09-16 03:36:01.060 NVP: A/V Divergence: -0.23905, Rate: -0.1953, Warpfactor: 0.996523, warpfactor_avg: 0.999998
9642006-09-16 03:36:01.061 AO: audio waiting for buffer to fill: have 2560 want 4096
9652006-09-16 03:36:01.062 AO: Broadcasting free space avail
9662006-09-16 03:36:01.069 AO: audio waiting for buffer to fill: have 2560 want 4096
9672006-09-16 03:36:01.069 AO: Broadcasting free space avail
9682006-09-16 03:36:01.080 AO: audio waiting for buffer to fill: have 2560 want 4096
9692006-09-16 03:36:01.081 AO: Broadcasting free space avail
9702006-09-16 03:36:01.082 AFD: video timecode 165637 154837 1720 1788
9712006-09-16 03:36:01.086 AFD: audio timecode 160929 160929 1788 1812
9722006-09-16 03:36:01.087 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1788
9732006-09-16 03:36:01.088 AFD: audio timecode 163089 163089 1812 1836
9742006-09-16 03:36:01.089 AO: Broadcasting free space avail
9752006-09-16 03:36:01.089 AO: audio waiting for buffer to fill: have 3072 want 4096
9762006-09-16 03:36:01.089 AO: Broadcasting free space avail
9772006-09-16 03:36:01.098 NVP: A/V Divergence: -0.366775, Rate: -0.127725, Warpfactor: 0.996042, warpfactor_avg: 0.999998
9782006-09-16 03:36:01.099 AO: audio waiting for buffer to fill: have 3072 want 4096
9792006-09-16 03:36:01.099 AO: Broadcasting free space avail
9802006-09-16 03:36:01.100 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=1812
9812006-09-16 03:36:01.105 AO: Broadcasting free space avail
9822006-09-16 03:36:01.105 AO: audio waiting for buffer to fill: have 3584 want 4096
9832006-09-16 03:36:01.105 AO: Broadcasting free space avail
9842006-09-16 03:36:01.114 AO: audio waiting for buffer to fill: have 3584 want 4096
9852006-09-16 03:36:01.114 AO: Broadcasting free space avail
9862006-09-16 03:36:01.121 AO: audio waiting for buffer to fill: have 3584 want 4096
9872006-09-16 03:36:01.121 AO: Broadcasting free space avail
9882006-09-16 03:36:01.132 AO: audio waiting for buffer to fill: have 3584 want 4096
9892006-09-16 03:36:01.132 AO: Broadcasting free space avail
9902006-09-16 03:36:01.133 NVP: prebuffering pause
9912006-09-16 03:36:01.134 NVP: Waiting for prebuffer.. 0 AAAAAdAALLAAAAAA
9922006-09-16 03:36:01.134 AFD: video timecode 158437 158437 1760 1836
9932006-09-16 03:36:01.136 AFD: audio timecode 165249 165249 1836 1860
9942006-09-16 03:36:01.136 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=1836
9952006-09-16 03:36:01.141 AO: OutputAudioLoop: audio paused
9962006-09-16 03:36:01.141 AO: 54656 bytes free on soundcard
9972006-09-16 03:36:01.152 NVP: A/V Divergence: -0.481325, Rate: -0.11455, Warpfactor: 0.995544, warpfactor_avg: 0.999997
9982006-09-16 03:36:01.152 AO: OutputAudioLoop: Play Event
9992006-09-16 03:36:01.153 AO: Broadcasting free space avail
10002006-09-16 03:36:01.153 AO: Broadcasting free space avail
10012006-09-16 03:36:01.153 AO: Broadcasting free space avail
10022006-09-16 03:36:01.165 AO: Broadcasting free space avail
10032006-09-16 03:36:01.167 NVP: prebuffering pause
10042006-09-16 03:36:01.167 NVP: Waiting for prebuffer.. 0 AAAALdAALDAAAAAA
10052006-09-16 03:36:01.173 AO: OutputAudioLoop: audio paused
10062006-09-16 03:36:01.173 AO: 52640 bytes free on soundcard
10072006-09-16 03:36:01.183 AO: 54560 bytes free on soundcard
10082006-09-16 03:36:01.184 AFD: video timecode 162037 162037 1800 1860
10092006-09-16 03:36:01.187 AFD: audio timecode 167409 167409 1860 1884
10102006-09-16 03:36:01.188 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=1860
10112006-09-16 03:36:01.189 AO: 55680 bytes free on soundcard
10122006-09-16 03:36:01.191 AFD: audio timecode 169569 169569 1884 1908
10132006-09-16 03:36:01.192 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=1884
10142006-09-16 03:36:01.197 AO: 57216 bytes free on soundcard
10152006-09-16 03:36:01.205 AO: 58752 bytes free on soundcard
10162006-09-16 03:36:01.213 AO: 60256 bytes free on soundcard
10172006-09-16 03:36:01.220 NVP: A/V Divergence: -0.523475, Rate: -0.04215, Warpfactor: 0.995472, warpfactor_avg: 0.999997
10182006-09-16 03:36:01.221 AO: OutputAudioLoop: Play Event
10192006-09-16 03:36:01.221 AO: Broadcasting free space avail
10202006-09-16 03:36:01.222 AO: Broadcasting free space avail
10212006-09-16 03:36:01.222 AO: audio waiting for buffer to fill: have 1024 want 4096
10222006-09-16 03:36:01.222 AO: Broadcasting free space avail
10232006-09-16 03:36:01.223 AFD: video timecode 176437 165637 1840 1908
10242006-09-16 03:36:01.224 AFD: audio timecode 171729 171729 1908 1932
10252006-09-16 03:36:01.225 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=1908
10262006-09-16 03:36:01.228 AFD: audio timecode 173889 173889 1932 1956
10272006-09-16 03:36:01.234 NVP: A/V Divergence: -0.7051, Rate: -0.181625, Warpfactor: 0.994975, warpfactor_avg: 0.999997
10282006-09-16 03:36:01.228 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=1932
10292006-09-16 03:36:01.235 AO: Broadcasting free space avail
10302006-09-16 03:36:01.235 AO: Broadcasting free space avail
10312006-09-16 03:36:01.236 AO: audio waiting for buffer to fill: have 2048 want 4096
10322006-09-16 03:36:01.236 AO: Broadcasting free space avail
10332006-09-16 03:36:01.241 AO: audio waiting for buffer to fill: have 2048 want 4096
10342006-09-16 03:36:01.242 AO: Broadcasting free space avail
10352006-09-16 03:36:01.249 AO: audio waiting for buffer to fill: have 2048 want 4096
10362006-09-16 03:36:01.250 AO: Broadcasting free space avail
10372006-09-16 03:36:01.251 NVP: prebuffering pause
10382006-09-16 03:36:01.252 NVP: Waiting for prebuffer.. 0 AAAAAAALdAALAAAA
10392006-09-16 03:36:01.257 AO: OutputAudioLoop: audio paused
10402006-09-16 03:36:01.257 AO: 52384 bytes free on soundcard
10412006-09-16 03:36:01.266 AO: 54080 bytes free on soundcard
10422006-09-16 03:36:01.273 AO: 55392 bytes free on soundcard
10432006-09-16 03:36:01.274 AFD: video timecode 169237 169237 1880 1956
10442006-09-16 03:36:01.276 AFD: audio timecode 176049 176049 1956 1980
10452006-09-16 03:36:01.276 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=1956
10462006-09-16 03:36:01.282 AO: 57152 bytes free on soundcard
10472006-09-16 03:36:01.289 AO: 58464 bytes free on soundcard
10482006-09-16 03:36:01.293 NVP: A/V Divergence: -0.722575, Rate: -0.017475, Warpfactor: 0.994477, warpfactor_avg: 0.999996
10492006-09-16 03:36:01.299 AO: OutputAudioLoop: Play Event
10502006-09-16 03:36:01.299 AO: Broadcasting free space avail
10512006-09-16 03:36:01.300 AO: audio waiting for buffer to fill: have 2560 want 4096
10522006-09-16 03:36:01.300 AO: Broadcasting free space avail
10532006-09-16 03:36:01.305 AO: audio waiting for buffer to fill: have 2560 want 4096
10542006-09-16 03:36:01.305 AO: Broadcasting free space avail
10552006-09-16 03:36:01.317 AO: audio waiting for buffer to fill: have 2560 want 4096
10562006-09-16 03:36:01.317 AO: Broadcasting free space avail
10572006-09-16 03:36:01.319 NVP: prebuffering pause
10582006-09-16 03:36:01.319 NVP: Waiting for prebuffer.. 0 AAAAAAADdAALLAAA
10592006-09-16 03:36:01.320 AFD: video timecode 172837 172837 1920 1980
10602006-09-16 03:36:01.322 AFD: audio timecode 178209 178209 1980 2004
10612006-09-16 03:36:01.322 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=1980
10622006-09-16 03:36:01.325 AO: OutputAudioLoop: audio paused
10632006-09-16 03:36:01.325 AO: 61344 bytes free on soundcard
10642006-09-16 03:36:01.324 AFD: audio timecode 180369 180369 2004 2028
10652006-09-16 03:36:01.327 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=2004
10662006-09-16 03:36:01.335 AO: 63296 bytes free on soundcard
10672006-09-16 03:36:01.341 AO: 60288 bytes free on soundcard
10682006-09-16 03:36:01.351 AO: 62240 bytes free on soundcard
10692006-09-16 03:36:01.357 AO: 59232 bytes free on soundcard
10702006-09-16 03:36:01.361 NVP: A/V Divergence: -0.910675, Rate: -0.1881, Warpfactor: 0.99398, warpfactor_avg: 0.999996
10712006-09-16 03:36:01.362 AFD: video timecode 187237 176437 1960 2028
10722006-09-16 03:36:01.363 AFD: audio timecode 182529 182529 2028 2052
10732006-09-16 03:36:01.364 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=2028
10742006-09-16 03:36:01.368 AO: OutputAudioLoop: Play Event
10752006-09-16 03:36:01.368 AO: Broadcasting free space avail
10762006-09-16 03:36:01.369 AO: Broadcasting free space avail
10772006-09-16 03:36:01.369 AO: Broadcasting free space avail
10782006-09-16 03:36:01.369 AO: Broadcasting free space avail
10792006-09-16 03:36:01.369 AO: Broadcasting free space avail
10802006-09-16 03:36:01.373 AFD: audio timecode 184689 184689 2052 2076
10812006-09-16 03:36:01.374 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2052
10822006-09-16 03:36:01.380 NVP: A/V Divergence: -0.88925, Rate: 0.021425, Warpfactor: 0.993483, warpfactor_avg: 0.999996
10832006-09-16 03:36:01.380 AO: Broadcasting free space avail
10842006-09-16 03:36:01.381 AO: audio waiting for buffer to fill: have 512 want 4096
10852006-09-16 03:36:01.381 AO: Broadcasting free space avail
10862006-09-16 03:36:01.389 AO: audio waiting for buffer to fill: have 512 want 4096
10872006-09-16 03:36:01.390 AO: Broadcasting free space avail
10882006-09-16 03:36:01.392 NVP: prebuffering pause
10892006-09-16 03:36:01.392 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAdAALL
10902006-09-16 03:36:01.397 AO: OutputAudioLoop: audio paused
10912006-09-16 03:36:01.400 AO: 46912 bytes free on soundcard
10922006-09-16 03:36:01.405 AO: 47936 bytes free on soundcard
10932006-09-16 03:36:01.413 AO: 49568 bytes free on soundcard
10942006-09-16 03:36:01.421 AO: 51040 bytes free on soundcard
10952006-09-16 03:36:01.425 AFD: video timecode 180037 180037 2000 2076
10962006-09-16 03:36:01.429 AO: 52704 bytes free on soundcard
10972006-09-16 03:36:01.432 AFD: audio timecode 186849 186849 2076 2100
10982006-09-16 03:36:01.437 AO: 54144 bytes free on soundcard
10992006-09-16 03:36:01.436 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2076
11002006-09-16 03:36:01.445 AO: 55712 bytes free on soundcard
11012006-09-16 03:36:01.453 AO: 57152 bytes free on soundcard
11022006-09-16 03:36:01.457 NVP: A/V Divergence: -0.723175, Rate: 0.166075, Warpfactor: 0.99398, warpfactor_avg: 0.999995
11032006-09-16 03:36:01.459 NVP: prebuffering pause
11042006-09-16 03:36:01.459 NVP: Waiting for prebuffer.. 0 AAAAAAAAAALdAALD
11052006-09-16 03:36:01.461 AO: OutputAudioLoop: audio paused
11062006-09-16 03:36:01.461 AO: 58816 bytes free on soundcard
11072006-09-16 03:36:01.471 AO: 60640 bytes free on soundcard
11082006-09-16 03:36:01.472 AFD: video timecode 183637 183637 2040 2100
11092006-09-16 03:36:01.474 AFD: audio timecode 189009 189009 2100 2124
11102006-09-16 03:36:01.475 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=2100
11112006-09-16 03:36:01.476 AFD: audio timecode 191169 191169 2124 2148
11122006-09-16 03:36:01.477 AO: 61824 bytes free on soundcard
11132006-09-16 03:36:01.479 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=2124
11142006-09-16 03:36:01.490 AO: 60160 bytes free on soundcard
11152006-09-16 03:36:01.490 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.994476, warpfactor_avg: 0.999995
11162006-09-16 03:36:01.497 AO: OutputAudioLoop: Play Event
11172006-09-16 03:36:01.497 AO: Broadcasting free space avail
11182006-09-16 03:36:01.497 AO: Broadcasting free space avail
11192006-09-16 03:36:01.498 AO: Broadcasting free space avail
11202006-09-16 03:36:01.498 AO: audio waiting for buffer to fill: have 2048 want 4096
11212006-09-16 03:36:01.498 AO: Broadcasting free space avail
11222006-09-16 03:36:01.505 AO: audio waiting for buffer to fill: have 2048 want 4096
11232006-09-16 03:36:01.505 AO: Broadcasting free space avail
11242006-09-16 03:36:01.513 AO: audio waiting for buffer to fill: have 2048 want 4096
11252006-09-16 03:36:01.513 AO: Broadcasting free space avail
11262006-09-16 03:36:01.520 NVP: prebuffering pause
11272006-09-16 03:36:01.520 NVP: Waiting for prebuffer.. 0 AAALAAAAAADDAALA
11282006-09-16 03:36:01.521 AO: OutputAudioLoop: audio paused
11292006-09-16 03:36:01.521 AO: 53952 bytes free on soundcard
11302006-09-16 03:36:01.522 AFD: video timecode 198037 187237 2080 2148
11312006-09-16 03:36:01.525 AFD: audio timecode 193328 193328 2148 2172
11322006-09-16 03:36:01.526 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2148
11332006-09-16 03:36:01.527 AFD: audio timecode 195488 195488 2172 2196
11342006-09-16 03:36:01.527 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2172
11352006-09-16 03:36:01.529 AO: 55552 bytes free on soundcard
11362006-09-16 03:36:01.538 AO: 57280 bytes free on soundcard
11372006-09-16 03:36:01.546 AO: 58784 bytes free on soundcard
11382006-09-16 03:36:01.555 NVP: A/V Divergence: -0.21875, Rate: -0.21875, Warpfactor: 0.994974, warpfactor_avg: 0.999994
11392006-09-16 03:36:01.556 AO: OutputAudioLoop: Play Event
11402006-09-16 03:36:01.557 AO: Broadcasting free space avail
11412006-09-16 03:36:01.557 AO: Broadcasting free space avail
11422006-09-16 03:36:01.557 AO: audio waiting for buffer to fill: have 3072 want 4096
11432006-09-16 03:36:01.557 AO: Broadcasting free space avail
11442006-09-16 03:36:01.560 NVP: prebuffering pause
11452006-09-16 03:36:01.560 NVP: Waiting for prebuffer.. 0 LAALAAAAAAAAAAdA
11462006-09-16 03:36:01.565 AO: OutputAudioLoop: audio paused
11472006-09-16 03:36:01.565 AO: 54144 bytes free on soundcard
11482006-09-16 03:36:01.571 AFD: video timecode 190837 190837 2120 2196
11492006-09-16 03:36:01.573 AO: 55712 bytes free on soundcard
11502006-09-16 03:36:01.574 AFD: audio timecode 197648 197648 2196 2220
11512006-09-16 03:36:01.574 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2196
11522006-09-16 03:36:01.581 AO: 57216 bytes free on soundcard
11532006-09-16 03:36:01.593 NVP: A/V Divergence: -0.3828, Rate: -0.16405, Warpfactor: 0.995471, warpfactor_avg: 0.999994
11542006-09-16 03:36:01.594 AO: OutputAudioLoop: Play Event
11552006-09-16 03:36:01.594 AO: Broadcasting free space avail
11562006-09-16 03:36:01.595 AO: audio waiting for buffer to fill: have 3584 want 4096
11572006-09-16 03:36:01.595 AO: Broadcasting free space avail
11582006-09-16 03:36:01.601 AO: audio waiting for buffer to fill: have 3584 want 4096
11592006-09-16 03:36:01.601 AO: Broadcasting free space avail
11602006-09-16 03:36:01.606 NVP: prebuffering pause
11612006-09-16 03:36:01.606 NVP: Waiting for prebuffer.. 0 DAALAAAAAAAAALdA
11622006-09-16 03:36:01.607 AFD: video timecode 194437 194437 2160 2220
11632006-09-16 03:36:01.609 AO: OutputAudioLoop: audio paused
11642006-09-16 03:36:01.609 AO: 58592 bytes free on soundcard
11652006-09-16 03:36:01.612 AFD: audio timecode 199808 199808 2220 2244
11662006-09-16 03:36:01.612 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2220
11672006-09-16 03:36:01.614 AFD: audio timecode 201968 201968 2244 2268
11682006-09-16 03:36:01.614 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=2244
11692006-09-16 03:36:01.617 AO: 60160 bytes free on soundcard
11702006-09-16 03:36:01.625 AO: 61600 bytes free on soundcard
11712006-09-16 03:36:01.633 AO: 58976 bytes free on soundcard
11722006-09-16 03:36:01.639 NVP: A/V Divergence: -0.5496, Rate: -0.1668, Warpfactor: 0.994973, warpfactor_avg: 0.999994
11732006-09-16 03:36:01.642 AO: OutputAudioLoop: Play Event
11742006-09-16 03:36:01.642 AO: Broadcasting free space avail
11752006-09-16 03:36:01.643 AO: Broadcasting free space avail
11762006-09-16 03:36:01.643 AO: Broadcasting free space avail
11772006-09-16 03:36:01.643 AO: audio waiting for buffer to fill: have 512 want 4096
11782006-09-16 03:36:01.643 AO: Broadcasting free space avail
11792006-09-16 03:36:01.644 NVP: prebuffering pause
11802006-09-16 03:36:01.644 NVP: Waiting for prebuffer.. 0 AAALAALAAAAAADDA
11812006-09-16 03:36:01.649 AO: OutputAudioLoop: audio paused
11822006-09-16 03:36:01.650 AO: 49984 bytes free on soundcard
11832006-09-16 03:36:01.657 AO: 51360 bytes free on soundcard
11842006-09-16 03:36:01.661 AFD: video timecode 208837 198037 2200 2268
11852006-09-16 03:36:01.662 AFD: audio timecode 204128 204128 2268 2292
11862006-09-16 03:36:01.663 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2268
11872006-09-16 03:36:01.667 AO: 53216 bytes free on soundcard
11882006-09-16 03:36:01.675 AO: 54816 bytes free on soundcard
11892006-09-16 03:36:01.668 AFD: audio timecode 206288 206288 2292 2316
11902006-09-16 03:36:01.676 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=2292
11912006-09-16 03:36:01.681 AO: 55936 bytes free on soundcard
11922006-09-16 03:36:01.690 AO: 57760 bytes free on soundcard
11932006-09-16 03:36:01.697 AO: 58976 bytes free on soundcard
11942006-09-16 03:36:01.704 NVP: A/V Divergence: -0.5247, Rate: 0.0249, Warpfactor: 0.995471, warpfactor_avg: 0.999993
11952006-09-16 03:36:01.705 AO: OutputAudioLoop: Play Event
11962006-09-16 03:36:01.708 AO: Broadcasting free space avail
11972006-09-16 03:36:01.708 AO: Broadcasting free space avail
11982006-09-16 03:36:01.708 AO: audio waiting for buffer to fill: have 1536 want 4096
11992006-09-16 03:36:01.708 AO: Broadcasting free space avail
12002006-09-16 03:36:01.711 NVP: prebuffering pause
12012006-09-16 03:36:01.711 NVP: Waiting for prebuffer.. 0 ALAdAALAAAAAAAAA
12022006-09-16 03:36:01.716 AFD: video timecode 201637 201637 2240 2316
12032006-09-16 03:36:01.717 AO: OutputAudioLoop: audio paused
12042006-09-16 03:36:01.718 AO: 54848 bytes free on soundcard
12052006-09-16 03:36:01.719 AFD: audio timecode 208448 208448 2316 2340
12062006-09-16 03:36:01.720 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2316
12072006-09-16 03:36:01.729 AO: OutputAudioLoop: Play Event
12082006-09-16 03:36:01.729 AO: Broadcasting free space avail
12092006-09-16 03:36:01.729 AO: audio waiting for buffer to fill: have 2048 want 4096
12102006-09-16 03:36:01.730 AO: Broadcasting free space avail
12112006-09-16 03:36:01.728 NVP: A/V Divergence: -0.674775, Rate: -0.150075, Warpfactor: 0.994973, warpfactor_avg: 0.999993
12122006-09-16 03:36:01.737 AO: audio waiting for buffer to fill: have 2048 want 4096
12132006-09-16 03:36:01.737 AO: Broadcasting free space avail
12142006-09-16 03:36:01.746 AO: audio waiting for buffer to fill: have 2048 want 4096
12152006-09-16 03:36:01.747 AO: Broadcasting free space avail
12162006-09-16 03:36:01.748 NVP: prebuffering pause
12172006-09-16 03:36:01.748 NVP: Waiting for prebuffer.. 0 ADLdAALAAAAAAAAA
12182006-09-16 03:36:01.753 AO: OutputAudioLoop: audio paused
12192006-09-16 03:36:01.753 AO: 57536 bytes free on soundcard
12202006-09-16 03:36:01.761 AO: 58976 bytes free on soundcard
12212006-09-16 03:36:01.761 AFD: video timecode 205237 205237 2280 2340
12222006-09-16 03:36:01.763 AFD: audio timecode 210608 210608 2340 2364
12232006-09-16 03:36:01.763 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2340
12242006-09-16 03:36:01.767 AFD: audio timecode 212768 212768 2364 2388
12252006-09-16 03:36:01.767 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=2364
12262006-09-16 03:36:01.769 AO: 60608 bytes free on soundcard
12272006-09-16 03:36:01.779 AO: 62592 bytes free on soundcard
12282006-09-16 03:36:01.785 AO: 59552 bytes free on soundcard
12292006-09-16 03:36:01.794 AO: 61344 bytes free on soundcard
12302006-09-16 03:36:01.801 AO: 62592 bytes free on soundcard
12312006-09-16 03:36:01.805 NVP: A/V Divergence: -0.768575, Rate: -0.0938, Warpfactor: 0.994476, warpfactor_avg: 0.999993
12322006-09-16 03:36:01.807 AFD: video timecode 219637 208837 2320 2388
12332006-09-16 03:36:01.809 AO: OutputAudioLoop: Play Event
12342006-09-16 03:36:01.809 AO: Broadcasting free space avail
12352006-09-16 03:36:01.812 AO: Broadcasting free space avail
12362006-09-16 03:36:01.812 AO: audio waiting for buffer to fill: have 3072 want 4096
12372006-09-16 03:36:01.812 AO: Broadcasting free space avail
12382006-09-16 03:36:01.817 AO: audio waiting for buffer to fill: have 3072 want 4096
12392006-09-16 03:36:01.818 AO: Broadcasting free space avail
12402006-09-16 03:36:01.822 NVP: A/V Divergence: -0.763925, Rate: 0.00465, Warpfactor: 0.993978, warpfactor_avg: 0.999992
12412006-09-16 03:36:01.808 AFD: audio timecode 214928 214928 2388 2412
12422006-09-16 03:36:01.823 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2388
12432006-09-16 03:36:01.825 AO: Broadcasting free space avail
12442006-09-16 03:36:01.825 AO: audio waiting for buffer to fill: have 3584 want 4096
12452006-09-16 03:36:01.827 AO: Broadcasting free space avail
12462006-09-16 03:36:01.824 AFD: audio timecode 217088 217088 2412 2436
12472006-09-16 03:36:01.831 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2412
12482006-09-16 03:36:01.833 AO: Broadcasting free space avail
12492006-09-16 03:36:01.833 AO: Broadcasting free space avail
12502006-09-16 03:36:01.834 AO: Broadcasting free space avail
12512006-09-16 03:36:01.838 NVP: prebuffering pause
12522006-09-16 03:36:01.838 NVP: Waiting for prebuffer.. 0 AAAAALdAALAAAAAA
12532006-09-16 03:36:01.841 AO: OutputAudioLoop: audio paused
12542006-09-16 03:36:01.841 AO: 45760 bytes free on soundcard
12552006-09-16 03:36:01.849 AO: 47264 bytes free on soundcard
12562006-09-16 03:36:01.853 AFD: video timecode 212437 212437 2360 2436
12572006-09-16 03:36:01.856 AFD: audio timecode 219248 219248 2436 2460
12582006-09-16 03:36:01.857 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2436
12592006-09-16 03:36:01.861 AO: 49504 bytes free on soundcard
12602006-09-16 03:36:01.875 AO: 52224 bytes free on soundcard
12612006-09-16 03:36:01.881 AFD: video timecode 216037 216037 2400 2460
12622006-09-16 03:36:01.882 AFD: audio timecode 221408 221408 2460 2484
12632006-09-16 03:36:01.883 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=2460
12642006-09-16 03:36:01.884 AFD: audio timecode 223568 223568 2484 2508
12652006-09-16 03:36:01.885 AO: 54112 bytes free on soundcard
12662006-09-16 03:36:01.891 NVP: A/V Divergence: -0.635425, Rate: 0.1285, Warpfactor: 0.994475, warpfactor_avg: 0.999992
12672006-09-16 03:36:01.884 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=2484
12682006-09-16 03:36:01.897 AO: OutputAudioLoop: Play Event
12692006-09-16 03:36:01.897 AO: Broadcasting free space avail
12702006-09-16 03:36:01.898 AO: Broadcasting free space avail
12712006-09-16 03:36:01.898 AO: Broadcasting free space avail
12722006-09-16 03:36:01.898 AO: audio waiting for buffer to fill: have 1536 want 4096
12732006-09-16 03:36:01.899 AO: Broadcasting free space avail
12742006-09-16 03:36:01.913 AO: audio waiting for buffer to fill: have 1536 want 4096
12752006-09-16 03:36:01.913 AO: Broadcasting free space avail
12762006-09-16 03:36:01.924 AO: audio waiting for buffer to fill: have 1536 want 4096
12772006-09-16 03:36:01.925 AO: Broadcasting free space avail
12782006-09-16 03:36:01.925 AFD: video timecode 230437 219637 2440 2508
12792006-09-16 03:36:01.928 AFD: audio timecode 225728 225728 2508 2532
12802006-09-16 03:36:01.939 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2508
12812006-09-16 03:36:01.941 AFD: audio timecode 227888 227888 2532 2556
12822006-09-16 03:36:01.941 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=2532
12832006-09-16 03:36:01.943 AO: Broadcasting free space avail
12842006-09-16 03:36:01.944 AO: Broadcasting free space avail
12852006-09-16 03:36:01.944 AO: audio waiting for buffer to fill: have 2560 want 4096
12862006-09-16 03:36:01.944 AO: Broadcasting free space avail
12872006-09-16 03:36:01.949 NVP: A/V Divergence: -0.6828, Rate: -0.047375, Warpfactor: 0.994151, warpfactor_avg: 0.999992
12882006-09-16 03:36:01.951 AO: audio waiting for buffer to fill: have 2560 want 4096
12892006-09-16 03:36:01.951 AO: Broadcasting free space avail
12902006-09-16 03:36:01.966 AO: audio waiting for buffer to fill: have 2560 want 4096
12912006-09-16 03:36:01.967 AO: Broadcasting free space avail
12922006-09-16 03:36:01.973 AO: audio waiting for buffer to fill: have 2560 want 4096
12932006-09-16 03:36:01.973 AO: Broadcasting free space avail
12942006-09-16 03:36:01.979 AFD: video timecode 223237 223237 2480 2556
12952006-09-16 03:36:01.980 AFD: audio timecode 230048 230048 2556 2580
12962006-09-16 03:36:01.985 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2556
12972006-09-16 03:36:01.993 AO: Broadcasting free space avail
12982006-09-16 03:36:01.993 AO: audio waiting for buffer to fill: have 3072 want 4096
12992006-09-16 03:36:01.993 AO: Broadcasting free space avail
13002006-09-16 03:36:02.010 AO: audio waiting for buffer to fill: have 3072 want 4096
13012006-09-16 03:36:02.011 AO: Broadcasting free space avail
13022006-09-16 03:36:02.018 NVP: A/V Divergence: -0.91835, Rate: -0.23555, Warpfactor: 0.993653, warpfactor_avg: 0.999991
13032006-09-16 03:36:02.019 AO: audio waiting for buffer to fill: have 3072 want 4096
13042006-09-16 03:36:02.020 AO: Broadcasting free space avail
13052006-09-16 03:36:02.029 AO: audio waiting for buffer to fill: have 3072 want 4096
13062006-09-16 03:36:02.030 AO: Broadcasting free space avail
13072006-09-16 03:36:02.037 AO: audio waiting for buffer to fill: have 3072 want 4096
13082006-09-16 03:36:02.037 AO: Broadcasting free space avail
13092006-09-16 03:36:02.044 AFD: video timecode 226837 226837 2520 2580
13102006-09-16 03:36:02.047 AFD: audio timecode 232208 232208 2580 2604
13112006-09-16 03:36:02.048 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2580
13122006-09-16 03:36:02.049 AO: Broadcasting free space avail
13132006-09-16 03:36:02.050 AO: audio waiting for buffer to fill: have 3584 want 4096
13142006-09-16 03:36:02.050 AO: Broadcasting free space avail
13152006-09-16 03:36:02.052 AFD: audio timecode 234368 234368 2604 2628
13162006-09-16 03:36:02.052 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2604
13172006-09-16 03:36:02.065 AO: Broadcasting free space avail
13182006-09-16 03:36:02.065 AO: Broadcasting free space avail
13192006-09-16 03:36:02.065 AO: Broadcasting free space avail
13202006-09-16 03:36:02.074 AO: Broadcasting free space avail
13212006-09-16 03:36:02.081 AFD: video timecode 241237 230437 2560 2628
13222006-09-16 03:36:02.083 AFD: audio timecode 236528 236528 2628 2652
13232006-09-16 03:36:02.083 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2628
13242006-09-16 03:36:02.085 AO: Broadcasting free space avail
13252006-09-16 03:36:02.086 AO: audio waiting for buffer to fill: have 512 want 4096
13262006-09-16 03:36:02.086 AO: Broadcasting free space avail
13272006-09-16 03:36:02.090 AFD: audio timecode 238688 238688 2652 2676
13282006-09-16 03:36:02.091 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=2652
13292006-09-16 03:36:02.098 NVP: A/V Divergence: -1.13875, Rate: -0.2204, Warpfactor: 0.993157, warpfactor_avg: 0.999991
13302006-09-16 03:36:02.100 AO: Broadcasting free space avail
13312006-09-16 03:36:02.100 AO: audio waiting for buffer to fill: have 1024 want 4096
13322006-09-16 03:36:02.101 AO: Broadcasting free space avail
13332006-09-16 03:36:02.121 AO: audio waiting for buffer to fill: have 1024 want 4096
13342006-09-16 03:36:02.122 AO: Broadcasting free space avail
13352006-09-16 03:36:02.132 AO: audio waiting for buffer to fill: have 1024 want 4096
13362006-09-16 03:36:02.132 AO: Broadcasting free space avail
13372006-09-16 03:36:02.133 AFD: video timecode 234037 234037 2600 2676
13382006-09-16 03:36:02.134 AFD: audio timecode 240848 240848 2676 2700
13392006-09-16 03:36:02.135 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=2676
13402006-09-16 03:36:02.141 NVP: A/V Divergence: -1.56655, Rate: -0.4278, Warpfactor: 0.99266, warpfactor_avg: 0.99999
13412006-09-16 03:36:02.142 AO: Broadcasting free space avail
13422006-09-16 03:36:02.142 AO: audio waiting for buffer to fill: have 1536 want 4096
13432006-09-16 03:36:02.143 AO: Broadcasting free space avail
13442006-09-16 03:36:02.154 AO: audio waiting for buffer to fill: have 1536 want 4096
13452006-09-16 03:36:02.154 AO: Broadcasting free space avail
13462006-09-16 03:36:02.161 AO: audio waiting for buffer to fill: have 1536 want 4096
13472006-09-16 03:36:02.161 AO: Broadcasting free space avail
13482006-09-16 03:36:02.169 AFD: video timecode 237637 237637 2640 2700
13492006-09-16 03:36:02.171 AFD: audio timecode 243008 243008 2700 2724
13502006-09-16 03:36:02.171 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=2700
13512006-09-16 03:36:02.173 AO: Broadcasting free space avail
13522006-09-16 03:36:02.174 AO: audio waiting for buffer to fill: have 2048 want 4096
13532006-09-16 03:36:02.174 AO: Broadcasting free space avail
13542006-09-16 03:36:02.184 AFD: audio timecode 245168 245168 2724 2748
13552006-09-16 03:36:02.185 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2724
13562006-09-16 03:36:02.197 AO: Broadcasting free space avail
13572006-09-16 03:36:02.197 AO: audio waiting for buffer to fill: have 2560 want 4096
13582006-09-16 03:36:02.197 AO: Broadcasting free space avail
13592006-09-16 03:36:02.197 NVP: A/V Divergence: -1.9999, Rate: -0.43335, Warpfactor: 0.992164, warpfactor_avg: 0.99999
13602006-09-16 03:36:02.214 AO: audio waiting for buffer to fill: have 2560 want 4096
13612006-09-16 03:36:02.214 AO: Broadcasting free space avail
13622006-09-16 03:36:02.221 AFD: video timecode 252036 241237 2680 2748
13632006-09-16 03:36:02.222 AFD: audio timecode 247328 247328 2748 2772
13642006-09-16 03:36:02.223 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2748
13652006-09-16 03:36:02.225 AO: Broadcasting free space avail
13662006-09-16 03:36:02.225 AO: audio waiting for buffer to fill: have 3072 want 4096
13672006-09-16 03:36:02.225 AO: Broadcasting free space avail
13682006-09-16 03:36:02.224 AFD: audio timecode 249488 249488 2772 2796
13692006-09-16 03:36:02.226 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=2772
13702006-09-16 03:36:02.237 AO: Broadcasting free space avail
13712006-09-16 03:36:02.237 AO: audio waiting for buffer to fill: have 3584 want 4096
13722006-09-16 03:36:02.238 AO: Broadcasting free space avail
13732006-09-16 03:36:02.243 NVP: A/V Divergence: -2.49992, Rate: -0.500025, Warpfactor: 0.991668, warpfactor_avg: 0.999989
13742006-09-16 03:36:02.245 AO: audio waiting for buffer to fill: have 3584 want 4096
13752006-09-16 03:36:02.246 AO: Broadcasting free space avail
13762006-09-16 03:36:02.256 NVP: A/V Divergence: -2.73743, Rate: -0.2375, Warpfactor: 0.991172, warpfactor_avg: 0.999988
13772006-09-16 03:36:02.260 AO: audio waiting for buffer to fill: have 3584 want 4096
13782006-09-16 03:36:02.260 AO: Broadcasting free space avail
13792006-09-16 03:36:02.279 AO: audio waiting for buffer to fill: have 3584 want 4096
13802006-09-16 03:36:02.279 AO: Broadcasting free space avail
13812006-09-16 03:36:02.286 AO: audio waiting for buffer to fill: have 3584 want 4096
13822006-09-16 03:36:02.286 AO: Broadcasting free space avail
13832006-09-16 03:36:02.289 AFD: video timecode 244837 244837 2720 2796
13842006-09-16 03:36:02.296 NVP: A/V Divergence: -2.76555, Rate: -0.028125, Warpfactor: 0.990676, warpfactor_avg: 0.999988
13852006-09-16 03:36:02.302 AO: audio waiting for buffer to fill: have 3584 want 4096
13862006-09-16 03:36:02.302 AO: Broadcasting free space avail
13872006-09-16 03:36:02.300 AFD: audio timecode 251648 251648 2796 2820
13882006-09-16 03:36:02.303 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2796
13892006-09-16 03:36:02.314 AO: Broadcasting free space avail
13902006-09-16 03:36:02.315 AO: Broadcasting free space avail
13912006-09-16 03:36:02.315 AO: Broadcasting free space avail
13922006-09-16 03:36:02.320 NVP: A/V Divergence: -2.71165, Rate: 0.0539, Warpfactor: 0.990181, warpfactor_avg: 0.999987
13932006-09-16 03:36:02.322 AO: Broadcasting free space avail
13942006-09-16 03:36:02.334 AO: Broadcasting free space avail
13952006-09-16 03:36:02.341 AO: Broadcasting free space avail
13962006-09-16 03:36:02.347 AFD: video timecode 248437 248437 2760 2820
13972006-09-16 03:36:02.348 AFD: audio timecode 253808 253808 2820 2844
13982006-09-16 03:36:02.357 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=2820
13992006-09-16 03:36:02.359 AFD: audio timecode 255968 255968 2844 2868
14002006-09-16 03:36:02.359 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=2844
14012006-09-16 03:36:02.365 AO: Broadcasting free space avail
14022006-09-16 03:36:02.365 AO: Broadcasting free space avail
14032006-09-16 03:36:02.366 AO: audio waiting for buffer to fill: have 1024 want 4096
14042006-09-16 03:36:02.366 AO: Broadcasting free space avail
14052006-09-16 03:36:02.377 AO: audio waiting for buffer to fill: have 1024 want 4096
14062006-09-16 03:36:02.380 AO: Broadcasting free space avail
14072006-09-16 03:36:02.390 AO: audio waiting for buffer to fill: have 1024 want 4096
14082006-09-16 03:36:02.390 AO: Broadcasting free space avail
14092006-09-16 03:36:02.397 AFD: video timecode 262836 252036 2800 2868
14102006-09-16 03:36:02.401 AO: audio waiting for buffer to fill: have 1024 want 4096
14112006-09-16 03:36:02.401 AO: Broadcasting free space avail
14122006-09-16 03:36:02.400 AFD: audio timecode 258128 258128 2868 2892
14132006-09-16 03:36:02.405 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=2868
14142006-09-16 03:36:02.407 AFD: audio timecode 260288 260288 2892 2916
14152006-09-16 03:36:02.407 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=2892
14162006-09-16 03:36:02.409 NVP: A/V Divergence: -2.56498, Rate: 0.146675, Warpfactor: 0.989686, warpfactor_avg: 0.999987
14172006-09-16 03:36:02.410 AO: Broadcasting free space avail
14182006-09-16 03:36:02.411 AO: Broadcasting free space avail
14192006-09-16 03:36:02.411 AO: audio waiting for buffer to fill: have 2048 want 4096
14202006-09-16 03:36:02.411 AO: Broadcasting free space avail
14212006-09-16 03:36:02.417 AO: audio waiting for buffer to fill: have 2048 want 4096
14222006-09-16 03:36:02.418 AO: Broadcasting free space avail
14232006-09-16 03:36:02.431 AO: audio waiting for buffer to fill: have 2048 want 4096
14242006-09-16 03:36:02.431 AO: Broadcasting free space avail
14252006-09-16 03:36:02.437 AO: audio waiting for buffer to fill: have 2048 want 4096
14262006-09-16 03:36:02.437 AO: Broadcasting free space avail
14272006-09-16 03:36:02.446 AO: audio waiting for buffer to fill: have 2048 want 4096
14282006-09-16 03:36:02.447 AO: Broadcasting free space avail
14292006-09-16 03:36:02.448 AFD: video timecode 255636 255636 2840 2916
14302006-09-16 03:36:02.450 AFD: audio timecode 262448 262448 2916 2940
14312006-09-16 03:36:02.451 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=2916
14322006-09-16 03:36:02.458 NVP: A/V Divergence: -2.57372, Rate: -0.00875, Warpfactor: 0.989191, warpfactor_avg: 0.999986
14332006-09-16 03:36:02.459 AO: Broadcasting free space avail
14342006-09-16 03:36:02.459 AO: audio waiting for buffer to fill: have 2560 want 4096
14352006-09-16 03:36:02.460 AO: Broadcasting free space avail
14362006-09-16 03:36:02.470 AO: audio waiting for buffer to fill: have 2560 want 4096
14372006-09-16 03:36:02.471 AO: Broadcasting free space avail
14382006-09-16 03:36:02.482 AO: audio waiting for buffer to fill: have 2560 want 4096
14392006-09-16 03:36:02.483 AO: Broadcasting free space avail
14402006-09-16 03:36:02.489 AFD: video timecode 259236 259236 2880 2940
14412006-09-16 03:36:02.492 AFD: audio timecode 264607 264607 2940 2964
14422006-09-16 03:36:02.495 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=2940
14432006-09-16 03:36:02.495 AO: audio waiting for buffer to fill: have 7168 want 4096
14442006-09-16 03:36:02.499 AO: Broadcasting free space avail
14452006-09-16 03:36:02.500 AFD: audio timecode 266767 266767 2964 2988
14462006-09-16 03:36:02.501 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=2964
14472006-09-16 03:36:02.512 AO: Broadcasting free space avail
14482006-09-16 03:36:02.513 AO: Broadcasting free space avail
14492006-09-16 03:36:02.513 AO: audio waiting for buffer to fill: have 3584 want 4096
14502006-09-16 03:36:02.513 AO: Broadcasting free space avail
14512006-09-16 03:36:02.522 NVP: A/V Divergence: -2.78028, Rate: -0.20655, Warpfactor: 0.988696, warpfactor_avg: 0.999985
14522006-09-16 03:36:02.523 AO: audio waiting for buffer to fill: have 3584 want 4096
14532006-09-16 03:36:02.523 AO: Broadcasting free space avail
14542006-09-16 03:36:02.537 AO: audio waiting for buffer to fill: have 3584 want 4096
14552006-09-16 03:36:02.537 AO: Broadcasting free space avail
14562006-09-16 03:36:02.548 AO: audio waiting for buffer to fill: have 3584 want 4096
14572006-09-16 03:36:02.549 AO: Broadcasting free space avail
14582006-09-16 03:36:02.549 AFD: video timecode 273636 262836 2920 2988
14592006-09-16 03:36:02.551 AFD: audio timecode 268927 268927 2988 3012
14602006-09-16 03:36:02.551 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=2988
14612006-09-16 03:36:02.556 AFD: audio timecode 271087 271087 3012 3036
14622006-09-16 03:36:02.556 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3012
14632006-09-16 03:36:02.561 AO: Broadcasting free space avail
14642006-09-16 03:36:02.562 AO: Broadcasting free space avail
14652006-09-16 03:36:02.575 AO: Broadcasting free space avail
14662006-09-16 03:36:02.576 AO: audio waiting for buffer to fill: have 512 want 4096
14672006-09-16 03:36:02.576 AO: Broadcasting free space avail
14682006-09-16 03:36:02.584 AO: audio waiting for buffer to fill: have 512 want 4096
14692006-09-16 03:36:02.585 AO: Broadcasting free space avail
14702006-09-16 03:36:02.585 AFD: video timecode 266436 266436 2960 3036
14712006-09-16 03:36:02.587 AFD: audio timecode 273247 273247 3036 3060
14722006-09-16 03:36:02.587 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3036
14732006-09-16 03:36:02.604 AO: Broadcasting free space avail
14742006-09-16 03:36:02.604 AO: audio waiting for buffer to fill: have 1024 want 4096
14752006-09-16 03:36:02.613 AO: Broadcasting free space avail
14762006-09-16 03:36:02.617 AFD: video timecode 270036 270036 3000 3060
14772006-09-16 03:36:02.619 AFD: audio timecode 275407 275407 3060 3084
14782006-09-16 03:36:02.620 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3060
14792006-09-16 03:36:02.622 AFD: audio timecode 277567 277567 3084 3108
14802006-09-16 03:36:02.623 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=3084
14812006-09-16 03:36:02.629 NVP: A/V Divergence: -2.9977, Rate: -0.217425, Warpfactor: 0.988202, warpfactor_avg: 0.999984
14822006-09-16 03:36:02.635 AO: Broadcasting free space avail
14832006-09-16 03:36:02.635 AO: Broadcasting free space avail
14842006-09-16 03:36:02.636 AO: audio waiting for buffer to fill: have 2048 want 4096
14852006-09-16 03:36:02.636 AO: Broadcasting free space avail
14862006-09-16 03:36:02.645 AO: audio waiting for buffer to fill: have 2048 want 4096
14872006-09-16 03:36:02.645 AO: Broadcasting free space avail
14882006-09-16 03:36:02.651 NVP: A/V Divergence: -3.29827, Rate: -0.300575, Warpfactor: 0.987708, warpfactor_avg: 0.999983
14892006-09-16 03:36:02.653 AO: audio waiting for buffer to fill: have 2048 want 4096
14902006-09-16 03:36:02.653 AO: Broadcasting free space avail
14912006-09-16 03:36:02.653 NVP: Video is 3.29827 frames behind audio (too slow), skipping A/V wait.
14922006-09-16 03:36:02.664 NVP: A/V Divergence: -3.35495, Rate: -0.056675, Warpfactor: 0.987214, warpfactor_avg: 0.999983
14932006-09-16 03:36:02.666 AO: audio waiting for buffer to fill: have 2048 want 4096
14942006-09-16 03:36:02.667 AO: Broadcasting free space avail
14952006-09-16 03:36:02.669 NVP: Video is 3.35495 frames behind audio (too slow), skipping A/V wait.
14962006-09-16 03:36:02.684 AO: audio waiting for buffer to fill: have 2048 want 4096
14972006-09-16 03:36:02.684 AO: Broadcasting free space avail
14982006-09-16 03:36:02.695 AO: audio waiting for buffer to fill: have 2048 want 4096
14992006-09-16 03:36:02.696 AO: Broadcasting free space avail
15002006-09-16 03:36:02.700 AFD: video timecode 284436 273636 3040 3108
15012006-09-16 03:36:02.704 NVP: A/V Divergence: -3.2412, Rate: 0.11375, Warpfactor: 0.98672, warpfactor_avg: 0.999982
15022006-09-16 03:36:02.708 AFD: audio timecode 279727 279727 3108 3132
15032006-09-16 03:36:02.708 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3108
15042006-09-16 03:36:02.710 AFD: audio timecode 281887 281887 3132 3156
15052006-09-16 03:36:02.710 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=3132
15062006-09-16 03:36:02.712 AO: Broadcasting free space avail
15072006-09-16 03:36:02.716 AO: Broadcasting free space avail
15082006-09-16 03:36:02.716 AO: audio waiting for buffer to fill: have 3072 want 4096
15092006-09-16 03:36:02.716 AO: Broadcasting free space avail
15102006-09-16 03:36:02.717 NVP: Video is 3.2412 frames behind audio (too slow), skipping A/V wait.
15112006-09-16 03:36:02.737 AO: audio waiting for buffer to fill: have 3072 want 4096
15122006-09-16 03:36:02.737 AO: Broadcasting free space avail
15132006-09-16 03:36:02.748 AO: audio waiting for buffer to fill: have 3072 want 4096
15142006-09-16 03:36:02.749 AO: Broadcasting free space avail
15152006-09-16 03:36:02.753 AFD: video timecode 277236 277236 3080 3156
15162006-09-16 03:36:02.759 AFD: audio timecode 284047 284047 3156 3180
15172006-09-16 03:36:02.759 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3156
15182006-09-16 03:36:02.762 AO: Broadcasting free space avail
15192006-09-16 03:36:02.763 AO: audio waiting for buffer to fill: have 3584 want 4096
15202006-09-16 03:36:02.763 AO: Broadcasting free space avail
15212006-09-16 03:36:02.768 NVP: A/V Divergence: -3.07465, Rate: 0.16655, Warpfactor: 0.986227, warpfactor_avg: 0.999981
15222006-09-16 03:36:02.770 AO: audio waiting for buffer to fill: have 3584 want 4096
15232006-09-16 03:36:02.770 AO: Broadcasting free space avail
15242006-09-16 03:36:02.785 AO: audio waiting for buffer to fill: have 3584 want 4096
15252006-09-16 03:36:02.785 AO: Broadcasting free space avail
15262006-09-16 03:36:02.789 NVP: Video is 3.07465 frames behind audio (too slow), skipping A/V wait.
15272006-09-16 03:36:02.793 AO: audio waiting for buffer to fill: have 3584 want 4096
15282006-09-16 03:36:02.793 AO: Broadcasting free space avail
15292006-09-16 03:36:02.793 AFD: video timecode 280836 280836 3120 3180
15302006-09-16 03:36:02.795 AFD: audio timecode 286207 286207 3180 3204
15312006-09-16 03:36:02.795 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3180
15322006-09-16 03:36:02.797 AFD: audio timecode 288367 288367 3204 3228
15332006-09-16 03:36:02.797 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3204
15342006-09-16 03:36:02.806 AO: Broadcasting free space avail
15352006-09-16 03:36:02.806 AO: Broadcasting free space avail
15362006-09-16 03:36:02.806 AO: Broadcasting free space avail
15372006-09-16 03:36:02.807 AO: audio waiting for buffer to fill: have 512 want 4096
15382006-09-16 03:36:02.807 AO: Broadcasting free space avail
15392006-09-16 03:36:02.809 NVP: A/V Divergence: -3.09348, Rate: -0.018825, Warpfactor: 0.985734, warpfactor_avg: 0.99998
15402006-09-16 03:36:02.821 AO: audio waiting for buffer to fill: have 512 want 4096
15412006-09-16 03:36:02.821 AO: Broadcasting free space avail
15422006-09-16 03:36:02.826 NVP: Video is 3.09348 frames behind audio (too slow), skipping A/V wait.
15432006-09-16 03:36:02.829 AO: audio waiting for buffer to fill: have 512 want 4096
15442006-09-16 03:36:02.829 AO: Broadcasting free space avail
15452006-09-16 03:36:02.837 AFD: video timecode 295236 284436 3160 3228
15462006-09-16 03:36:02.840 AFD: audio timecode 290527 290527 3228 3252
15472006-09-16 03:36:02.840 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3228
15482006-09-16 03:36:02.846 AFD: audio timecode 292687 292687 3252 3276
15492006-09-16 03:36:02.847 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=3252
15502006-09-16 03:36:02.852 NVP: A/V Divergence: -2.9576, Rate: 0.135875, Warpfactor: 0.985241, warpfactor_avg: 0.999979
15512006-09-16 03:36:02.853 AO: Broadcasting free space avail
15522006-09-16 03:36:02.853 AO: Broadcasting free space avail
15532006-09-16 03:36:02.853 AO: audio waiting for buffer to fill: have 1536 want 4096
15542006-09-16 03:36:02.853 AO: Broadcasting free space avail
15552006-09-16 03:36:02.859 NVP: A/V Divergence: -2.7807, Rate: 0.1769, Warpfactor: 0.984748, warpfactor_avg: 0.999978
15562006-09-16 03:36:02.866 AO: audio waiting for buffer to fill: have 1536 want 4096
15572006-09-16 03:36:02.867 AO: Broadcasting free space avail
15582006-09-16 03:36:02.878 AO: audio waiting for buffer to fill: have 1536 want 4096
15592006-09-16 03:36:02.879 AO: Broadcasting free space avail
15602006-09-16 03:36:02.888 AO: audio waiting for buffer to fill: have 1536 want 4096
15612006-09-16 03:36:02.888 AO: Broadcasting free space avail
15622006-09-16 03:36:02.889 AFD: video timecode 288036 288036 3200 3276
15632006-09-16 03:36:02.891 AFD: audio timecode 294847 294847 3276 3300
15642006-09-16 03:36:02.891 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3276
15652006-09-16 03:36:02.905 AO: Broadcasting free space avail
15662006-09-16 03:36:02.906 AO: audio waiting for buffer to fill: have 2048 want 4096
15672006-09-16 03:36:02.906 AO: Broadcasting free space avail
15682006-09-16 03:36:02.896 NVP: A/V Divergence: -2.60427, Rate: 0.176425, Warpfactor: 0.984256, warpfactor_avg: 0.999977
15692006-09-16 03:36:02.915 AO: audio waiting for buffer to fill: have 2048 want 4096
15702006-09-16 03:36:02.916 AO: Broadcasting free space avail
15712006-09-16 03:36:02.922 AFD: video timecode 291636 291636 3240 3300
15722006-09-16 03:36:02.925 AFD: audio timecode 297007 297007 3300 3324
15732006-09-16 03:36:02.926 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3300
15742006-09-16 03:36:02.927 AFD: audio timecode 299167 299167 3324 3348
15752006-09-16 03:36:02.927 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=3324
15762006-09-16 03:36:02.929 AO: Broadcasting free space avail
15772006-09-16 03:36:02.929 AO: Broadcasting free space avail
15782006-09-16 03:36:02.929 AO: audio waiting for buffer to fill: have 3072 want 4096
15792006-09-16 03:36:02.930 AO: Broadcasting free space avail
15802006-09-16 03:36:02.936 NVP: A/V Divergence: -2.5032, Rate: 0.101075, Warpfactor: 0.983764, warpfactor_avg: 0.999976
15812006-09-16 03:36:02.941 AO: audio waiting for buffer to fill: have 3072 want 4096
15822006-09-16 03:36:02.942 AO: Broadcasting free space avail
15832006-09-16 03:36:02.954 AO: audio waiting for buffer to fill: have 3072 want 4096
15842006-09-16 03:36:02.955 AO: Broadcasting free space avail
15852006-09-16 03:36:02.963 AO: audio waiting for buffer to fill: have 3072 want 4096
15862006-09-16 03:36:02.964 AO: Broadcasting free space avail
15872006-09-16 03:36:02.972 AFD: video timecode 306036 295236 3280 3348
15882006-09-16 03:36:02.973 AFD: audio timecode 301327 301327 3348 3372
15892006-09-16 03:36:02.974 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3348
15902006-09-16 03:36:02.977 AO: Broadcasting free space avail
15912006-09-16 03:36:02.977 AO: audio waiting for buffer to fill: have 3584 want 4096
15922006-09-16 03:36:02.978 AO: Broadcasting free space avail
15932006-09-16 03:36:02.982 AFD: audio timecode 303487 303487 3372 3396
15942006-09-16 03:36:02.982 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3372
15952006-09-16 03:36:02.989 NVP: A/V Divergence: -2.37115, Rate: 0.13205, Warpfactor: 0.983272, warpfactor_avg: 0.999975
15962006-09-16 03:36:02.990 AO: Broadcasting free space avail
15972006-09-16 03:36:02.990 AO: Broadcasting free space avail
15982006-09-16 03:36:02.990 AO: Broadcasting free space avail
15992006-09-16 03:36:02.994 NVP: prebuffering pause
16002006-09-16 03:36:02.994 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAdAALAL
16012006-09-16 03:36:03.001 AO: OutputAudioLoop: audio paused
16022006-09-16 03:36:03.001 AO: 57888 bytes free on soundcard
16032006-09-16 03:36:03.011 AO: 59744 bytes free on soundcard
16042006-09-16 03:36:03.017 AFD: video timecode 298836 298836 3320 3396
16052006-09-16 03:36:03.018 AFD: audio timecode 305647 305647 3396 3420
16062006-09-16 03:36:03.019 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=3396
16072006-09-16 03:36:03.025 AO: 62400 bytes free on soundcard
16082006-09-16 03:36:03.036 AO: 60544 bytes free on soundcard
16092006-09-16 03:36:03.041 AFD: video timecode 302436 302436 3360 3420
16102006-09-16 03:36:03.043 AFD: audio timecode 307807 307807 3420 3444
16112006-09-16 03:36:03.043 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=3420
16122006-09-16 03:36:03.048 NVP: A/V Divergence: -2.2096, Rate: 0.16155, Warpfactor: 0.983591, warpfactor_avg: 0.999974
16132006-09-16 03:36:03.051 AFD: audio timecode 309967 309967 3444 3468
16142006-09-16 03:36:03.051 AO: _AddSamples bytes=4608, used=9217, free=758783, timecode=3444
16152006-09-16 03:36:03.054 AO: OutputAudioLoop: Play Event
16162006-09-16 03:36:03.054 AO: Broadcasting free space avail
16172006-09-16 03:36:03.055 AO: Broadcasting free space avail
16182006-09-16 03:36:03.055 AO: Broadcasting free space avail
16192006-09-16 03:36:03.055 AO: audio waiting for buffer to fill: have 1536 want 4096
16202006-09-16 03:36:03.055 AO: Broadcasting free space avail
16212006-09-16 03:36:03.069 AO: audio waiting for buffer to fill: have 1536 want 4096
16222006-09-16 03:36:03.069 AO: Broadcasting free space avail
16232006-09-16 03:36:03.077 AO: audio waiting for buffer to fill: have 1536 want 4096
16242006-09-16 03:36:03.078 AO: Broadcasting free space avail
16252006-09-16 03:36:03.081 AFD: video timecode 316836 306036 3400 3468
16262006-09-16 03:36:03.082 AFD: audio timecode 312127 312127 3468 3492
16272006-09-16 03:36:03.083 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3468
16282006-09-16 03:36:03.084 AFD: audio timecode 314287 314287 3492 3516
16292006-09-16 03:36:03.085 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=3492
16302006-09-16 03:36:03.093 AO: Broadcasting free space avail
16312006-09-16 03:36:03.094 AO: Broadcasting free space avail
16322006-09-16 03:36:03.094 AO: audio waiting for buffer to fill: have 2560 want 4096
16332006-09-16 03:36:03.094 AO: Broadcasting free space avail
16342006-09-16 03:36:03.103 AO: audio waiting for buffer to fill: have 2560 want 4096
16352006-09-16 03:36:03.103 AO: Broadcasting free space avail
16362006-09-16 03:36:03.112 AFD: video timecode 309636 309636 3440 3516
16372006-09-16 03:36:03.115 AFD: audio timecode 316447 316447 3516 3540
16382006-09-16 03:36:03.116 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=3516
16392006-09-16 03:36:03.117 AO: Broadcasting free space avail
16402006-09-16 03:36:03.117 AO: audio waiting for buffer to fill: have 3072 want 4096
16412006-09-16 03:36:03.117 AO: Broadcasting free space avail
16422006-09-16 03:36:03.123 NVP: A/V Divergence: -2.4822, Rate: -0.2726, Warpfactor: 0.983099, warpfactor_avg: 0.999972
16432006-09-16 03:36:03.125 AO: audio waiting for buffer to fill: have 3072 want 4096
16442006-09-16 03:36:03.126 AO: Broadcasting free space avail
16452006-09-16 03:36:03.133 NVP: A/V Divergence: -2.5054, Rate: -0.0232, Warpfactor: 0.982607, warpfactor_avg: 0.999971
16462006-09-16 03:36:03.134 AO: audio waiting for buffer to fill: have 3072 want 4096
16472006-09-16 03:36:03.134 AO: Broadcasting free space avail
16482006-09-16 03:36:03.143 AO: audio waiting for buffer to fill: have 3072 want 4096
16492006-09-16 03:36:03.143 AO: Broadcasting free space avail
16502006-09-16 03:36:03.148 NVP: A/V Divergence: -2.36655, Rate: 0.13885, Warpfactor: 0.98215, warpfactor_avg: 0.99997
16512006-09-16 03:36:03.149 AO: audio waiting for buffer to fill: have 3072 want 4096
16522006-09-16 03:36:03.150 AO: Broadcasting free space avail
16532006-09-16 03:36:03.153 NVP: prebuffering pause
16542006-09-16 03:36:03.154 NVP: Waiting for prebuffer.. 0 LADLAAAAAAAAAdAA
16552006-09-16 03:36:03.162 AO: OutputAudioLoop: audio paused
16562006-09-16 03:36:03.162 AO: 60096 bytes free on soundcard
16572006-09-16 03:36:03.169 AO: 61536 bytes free on soundcard
16582006-09-16 03:36:03.179 AFD: video timecode 313236 313236 3480 3540
16592006-09-16 03:36:03.181 AFD: audio timecode 318607 318607 3540 3564
16602006-09-16 03:36:03.181 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=3540
16612006-09-16 03:36:03.183 AFD: audio timecode 320767 320767 3564 3588
16622006-09-16 03:36:03.183 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=3564
16632006-09-16 03:36:03.185 AO: 60384 bytes free on soundcard
16642006-09-16 03:36:03.201 AO: 63488 bytes free on soundcard
16652006-09-16 03:36:03.209 AO: 60896 bytes free on soundcard
16662006-09-16 03:36:03.211 AFD: video timecode 327636 316836 3520 3588
16672006-09-16 03:36:03.215 AFD: audio timecode 322927 322927 3588 3612
16682006-09-16 03:36:03.215 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=3588
16692006-09-16 03:36:03.216 AFD: audio timecode 325087 325087 3612 3636
16702006-09-16 03:36:03.217 AO: _AddSamples bytes=4608, used=16897, free=751103, timecode=3612
16712006-09-16 03:36:03.225 NVP: A/V Divergence: -2.0624, Rate: 0.30415, Warpfactor: 0.982641, warpfactor_avg: 0.999969
16722006-09-16 03:36:03.226 AO: OutputAudioLoop: Play Event
16732006-09-16 03:36:03.226 AO: Broadcasting free space avail
16742006-09-16 03:36:03.227 AO: Broadcasting free space avail
16752006-09-16 03:36:03.227 AO: Broadcasting free space avail
16762006-09-16 03:36:03.227 AO: Broadcasting free space avail
16772006-09-16 03:36:03.228 AO: Broadcasting free space avail
16782006-09-16 03:36:03.228 AO: audio waiting for buffer to fill: have 1024 want 4096
16792006-09-16 03:36:03.230 AO: Broadcasting free space avail
16802006-09-16 03:36:03.237 NVP: A/V Divergence: -1.8218, Rate: 0.2406, Warpfactor: 0.983132, warpfactor_avg: 0.999968
16812006-09-16 03:36:03.238 AO: audio waiting for buffer to fill: have 1024 want 4096
16822006-09-16 03:36:03.238 AO: Broadcasting free space avail
16832006-09-16 03:36:03.252 AO: audio waiting for buffer to fill: have 1024 want 4096
16842006-09-16 03:36:03.252 AO: Broadcasting free space avail
16852006-09-16 03:36:03.258 AO: audio waiting for buffer to fill: have 1024 want 4096
16862006-09-16 03:36:03.259 AO: Broadcasting free space avail
16872006-09-16 03:36:03.261 AFD: video timecode 320436 320436 3560 3636
16882006-09-16 03:36:03.265 AFD: audio timecode 327247 327247 3636 3660
16892006-09-16 03:36:03.271 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3636
16902006-09-16 03:36:03.279 AO: Broadcasting free space avail
16912006-09-16 03:36:03.279 AO: audio waiting for buffer to fill: have 1536 want 4096
16922006-09-16 03:36:03.279 AO: Broadcasting free space avail
16932006-09-16 03:36:03.280 NVP: A/V Divergence: -1.55385, Rate: 0.26795, Warpfactor: 0.983624, warpfactor_avg: 0.999967
16942006-09-16 03:36:03.292 AO: audio waiting for buffer to fill: have 1536 want 4096
16952006-09-16 03:36:03.293 AO: Broadcasting free space avail
16962006-09-16 03:36:03.297 AFD: video timecode 324036 324036 3600 3660
16972006-09-16 03:36:03.306 AFD: audio timecode 329406 329406 3660 3684
16982006-09-16 03:36:03.306 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=3660
16992006-09-16 03:36:03.308 AFD: audio timecode 331566 331566 3684 3708
17002006-09-16 03:36:03.308 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=3684
17012006-09-16 03:36:03.309 AO: Broadcasting free space avail
17022006-09-16 03:36:03.309 AO: Broadcasting free space avail
17032006-09-16 03:36:03.309 AO: audio waiting for buffer to fill: have 2560 want 4096
17042006-09-16 03:36:03.310 AO: Broadcasting free space avail
17052006-09-16 03:36:03.320 NVP: A/V Divergence: -1.44662, Rate: 0.107225, Warpfactor: 0.984116, warpfactor_avg: 0.999966
17062006-09-16 03:36:03.324 AO: audio waiting for buffer to fill: have 2560 want 4096
17072006-09-16 03:36:03.328 AO: Broadcasting free space avail
17082006-09-16 03:36:03.330 NVP: prebuffering pause
17092006-09-16 03:36:03.330 NVP: Waiting for prebuffer.. 0 ADADLAAALAAAAAAA
17102006-09-16 03:36:03.341 AO: OutputAudioLoop: audio paused
17112006-09-16 03:36:03.342 AO: 53664 bytes free on soundcard
17122006-09-16 03:36:03.351 AO: 55424 bytes free on soundcard
17132006-09-16 03:36:03.357 AFD: video timecode 338436 327636 3640 3708
17142006-09-16 03:36:03.359 AFD: audio timecode 333726 333726 3708 3732
17152006-09-16 03:36:03.359 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=3708
17162006-09-16 03:36:03.362 AFD: audio timecode 335886 335886 3732 3756
17172006-09-16 03:36:03.363 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=3732
17182006-09-16 03:36:03.365 AO: 58080 bytes free on soundcard
17192006-09-16 03:36:03.378 AO: 60576 bytes free on soundcard
17202006-09-16 03:36:03.385 AFD: video timecode 331236 331236 3680 3756
17212006-09-16 03:36:03.387 AFD: audio timecode 338046 338046 3756 3780
17222006-09-16 03:36:03.387 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=3756
17232006-09-16 03:36:03.389 AO: 62720 bytes free on soundcard
17242006-09-16 03:36:03.398 NVP: A/V Divergence: -1.20995, Rate: 0.236675, Warpfactor: 0.984608, warpfactor_avg: 0.999965
17252006-09-16 03:36:03.399 AO: OutputAudioLoop: Play Event
17262006-09-16 03:36:03.400 AO: Broadcasting free space avail
17272006-09-16 03:36:03.400 AO: Broadcasting free space avail
17282006-09-16 03:36:03.400 AO: Broadcasting free space avail
17292006-09-16 03:36:03.401 AO: Broadcasting free space avail
17302006-09-16 03:36:03.401 AO: Broadcasting free space avail
17312006-09-16 03:36:03.405 NVP: A/V Divergence: -1.10745, Rate: 0.1025, Warpfactor: 0.9851, warpfactor_avg: 0.999964
17322006-09-16 03:36:03.417 AO: Broadcasting free space avail
17332006-09-16 03:36:03.428 AO: Broadcasting free space avail
17342006-09-16 03:36:03.429 AFD: video timecode 334836 334836 3720 3780
17352006-09-16 03:36:03.431 AFD: audio timecode 340206 340206 3780 3804
17362006-09-16 03:36:03.432 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=3780
17372006-09-16 03:36:03.435 AFD: audio timecode 342366 342366 3804 3828
17382006-09-16 03:36:03.436 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=3804
17392006-09-16 03:36:03.438 AO: Broadcasting free space avail
17402006-09-16 03:36:03.439 AO: Broadcasting free space avail
17412006-09-16 03:36:03.439 AO: audio waiting for buffer to fill: have 1024 want 4096
17422006-09-16 03:36:03.439 AO: Broadcasting free space avail
17432006-09-16 03:36:03.446 NVP: A/V Divergence: -1.01807, Rate: 0.089375, Warpfactor: 0.985593, warpfactor_avg: 0.999963
17442006-09-16 03:36:03.449 AO: audio waiting for buffer to fill: have 1024 want 4096
17452006-09-16 03:36:03.449 AO: Broadcasting free space avail
17462006-09-16 03:36:03.463 AO: audio waiting for buffer to fill: have 1024 want 4096
17472006-09-16 03:36:03.463 AO: Broadcasting free space avail
17482006-09-16 03:36:03.473 AO: audio waiting for buffer to fill: have 1024 want 4096
17492006-09-16 03:36:03.473 AO: Broadcasting free space avail
17502006-09-16 03:36:03.477 AFD: video timecode 349236 338436 3760 3828
17512006-09-16 03:36:03.479 AFD: audio timecode 344526 344526 3828 3852
17522006-09-16 03:36:03.480 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=3828
17532006-09-16 03:36:03.482 AFD: audio timecode 346686 346686 3852 3876
17542006-09-16 03:36:03.483 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=3852
17552006-09-16 03:36:03.486 AO: Broadcasting free space avail
17562006-09-16 03:36:03.486 AO: Broadcasting free space avail
17572006-09-16 03:36:03.486 AO: audio waiting for buffer to fill: have 2048 want 4096
17582006-09-16 03:36:03.487 AO: Broadcasting free space avail
17592006-09-16 03:36:03.491 NVP: A/V Divergence: -0.9948, Rate: 0.023275, Warpfactor: 0.986085, warpfactor_avg: 0.999962
17602006-09-16 03:36:03.497 AO: audio waiting for buffer to fill: have 2048 want 4096
17612006-09-16 03:36:03.497 AO: Broadcasting free space avail
17622006-09-16 03:36:03.511 AO: audio waiting for buffer to fill: have 2048 want 4096
17632006-09-16 03:36:03.511 AO: Broadcasting free space avail
17642006-09-16 03:36:03.517 AFD: video timecode 342036 342036 3800 3876
17652006-09-16 03:36:03.521 AO: audio waiting for buffer to fill: have 2048 want 4096
17662006-09-16 03:36:03.521 AO: Broadcasting free space avail
17672006-09-16 03:36:03.520 AFD: audio timecode 348846 348846 3876 3900
17682006-09-16 03:36:03.523 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=3876
17692006-09-16 03:36:03.531 NVP: A/V Divergence: -0.96485, Rate: 0.02995, Warpfactor: 0.986578, warpfactor_avg: 0.999961
17702006-09-16 03:36:03.538 AO: Broadcasting free space avail
17712006-09-16 03:36:03.538 AO: audio waiting for buffer to fill: have 2560 want 4096
17722006-09-16 03:36:03.538 AO: Broadcasting free space avail
17732006-09-16 03:36:03.548 AO: audio waiting for buffer to fill: have 2560 want 4096
17742006-09-16 03:36:03.549 AO: Broadcasting free space avail
17752006-09-16 03:36:03.553 AFD: video timecode 345636 345636 3840 3900
17762006-09-16 03:36:03.555 AFD: audio timecode 351006 351006 3900 3924
17772006-09-16 03:36:03.555 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=3900
17782006-09-16 03:36:03.557 AFD: audio timecode 353166 353166 3924 3948
17792006-09-16 03:36:03.557 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=3924
17802006-09-16 03:36:03.560 AO: Broadcasting free space avail
17812006-09-16 03:36:03.560 AO: Broadcasting free space avail
17822006-09-16 03:36:03.560 AO: audio waiting for buffer to fill: have 3584 want 4096
17832006-09-16 03:36:03.564 AO: Broadcasting free space avail
17842006-09-16 03:36:03.570 NVP: A/V Divergence: -0.948625, Rate: 0.016225, Warpfactor: 0.987072, warpfactor_avg: 0.99996
17852006-09-16 03:36:03.585 AO: audio waiting for buffer to fill: have 3584 want 4096
17862006-09-16 03:36:03.585 AO: Broadcasting free space avail
17872006-09-16 03:36:03.589 NVP: prebuffering pause
17882006-09-16 03:36:03.590 NVP: Waiting for prebuffer.. 0 AAAAAAADDAALAAAL
17892006-09-16 03:36:03.590 AFD: video timecode 360035 349236 3880 3948
17902006-09-16 03:36:03.595 AO: OutputAudioLoop: audio paused
17912006-09-16 03:36:03.595 AO: 53184 bytes free on soundcard
17922006-09-16 03:36:03.602 AO: 54496 bytes free on soundcard
17932006-09-16 03:36:03.603 AFD: audio timecode 355326 355326 3948 3972
17942006-09-16 03:36:03.604 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=3948
17952006-09-16 03:36:03.606 AFD: audio timecode 357486 357486 3972 3996
17962006-09-16 03:36:03.606 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=3972
17972006-09-16 03:36:03.609 AO: 55808 bytes free on soundcard
17982006-09-16 03:36:03.622 AO: 58080 bytes free on soundcard
17992006-09-16 03:36:03.628 NVP: A/V Divergence: -0.8302, Rate: 0.118425, Warpfactor: 0.987565, warpfactor_avg: 0.999959
18002006-09-16 03:36:03.629 AO: OutputAudioLoop: Play Event
18012006-09-16 03:36:03.630 AO: Broadcasting free space avail
18022006-09-16 03:36:03.631 AO: Broadcasting free space avail
18032006-09-16 03:36:03.631 AO: Broadcasting free space avail
18042006-09-16 03:36:03.631 AO: audio waiting for buffer to fill: have 512 want 4096
18052006-09-16 03:36:03.631 AO: Broadcasting free space avail
18062006-09-16 03:36:03.639 AO: audio waiting for buffer to fill: have 512 want 4096
18072006-09-16 03:36:03.640 AO: Broadcasting free space avail
18082006-09-16 03:36:03.646 AO: audio waiting for buffer to fill: have 512 want 4096
18092006-09-16 03:36:03.646 AO: Broadcasting free space avail
18102006-09-16 03:36:03.647 NVP: prebuffering pause
18112006-09-16 03:36:03.647 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAdAALL
18122006-09-16 03:36:03.655 AO: OutputAudioLoop: audio paused
18132006-09-16 03:36:03.656 AO: 52512 bytes free on soundcard
18142006-09-16 03:36:03.656 AFD: video timecode 352835 352835 3920 3996
18152006-09-16 03:36:03.658 AFD: audio timecode 359646 359646 3996 4020
18162006-09-16 03:36:03.658 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=3996
18172006-09-16 03:36:03.669 AO: 55008 bytes free on soundcard
18182006-09-16 03:36:03.680 AO: 57248 bytes free on soundcard
18192006-09-16 03:36:03.685 AFD: video timecode 356435 356435 3960 4020
18202006-09-16 03:36:03.690 AFD: audio timecode 361806 361806 4020 4044
18212006-09-16 03:36:03.691 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=4020
18222006-09-16 03:36:03.692 AFD: audio timecode 363966 363966 4044 4068
18232006-09-16 03:36:03.692 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=4044
18242006-09-16 03:36:03.696 NVP: A/V Divergence: -0.8539, Rate: -0.0237, Warpfactor: 0.988059, warpfactor_avg: 0.999958
18252006-09-16 03:36:03.698 AO: OutputAudioLoop: Play Event
18262006-09-16 03:36:03.713 AO: Broadcasting free space avail
18272006-09-16 03:36:03.714 AO: Broadcasting free space avail
18282006-09-16 03:36:03.714 AO: Broadcasting free space avail
18292006-09-16 03:36:03.714 AO: audio waiting for buffer to fill: have 2048 want 4096
18302006-09-16 03:36:03.714 AO: Broadcasting free space avail
18312006-09-16 03:36:03.726 AO: audio waiting for buffer to fill: have 2048 want 4096
18322006-09-16 03:36:03.727 AO: Broadcasting free space avail
18332006-09-16 03:36:03.729 AFD: video timecode 370835 360035 4000 4068
18342006-09-16 03:36:03.730 AFD: audio timecode 366126 366126 4068 4092
18352006-09-16 03:36:03.731 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=4068
18362006-09-16 03:36:03.735 AFD: audio timecode 368286 368286 4092 4116
18372006-09-16 03:36:03.735 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=4092
18382006-09-16 03:36:03.737 AO: Broadcasting free space avail
18392006-09-16 03:36:03.737 AO: Broadcasting free space avail
18402006-09-16 03:36:03.738 AO: audio waiting for buffer to fill: have 3072 want 4096
18412006-09-16 03:36:03.740 AO: Broadcasting free space avail
18422006-09-16 03:36:03.748 NVP: A/V Divergence: -1.23417, Rate: -0.380275, Warpfactor: 0.987565, warpfactor_avg: 0.999958
18432006-09-16 03:36:03.749 AO: audio waiting for buffer to fill: have 3072 want 4096
18442006-09-16 03:36:03.750 AO: Broadcasting free space avail
18452006-09-16 03:36:03.763 AO: audio waiting for buffer to fill: have 3072 want 4096
18462006-09-16 03:36:03.763 AO: Broadcasting free space avail
18472006-09-16 03:36:03.769 AO: audio waiting for buffer to fill: have 3072 want 4096
18482006-09-16 03:36:03.769 AO: Broadcasting free space avail
18492006-09-16 03:36:03.775 AFD: video timecode 363635 363635 4040 4116
18502006-09-16 03:36:03.782 AFD: audio timecode 370446 370446 4116 4140
18512006-09-16 03:36:03.783 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=4116
18522006-09-16 03:36:03.793 AO: Broadcasting free space avail
18532006-09-16 03:36:03.793 AO: audio waiting for buffer to fill: have 3584 want 4096
18542006-09-16 03:36:03.793 AO: Broadcasting free space avail
18552006-09-16 03:36:03.780 NVP: A/V Divergence: -1.41313, Rate: -0.17895, Warpfactor: 0.987221, warpfactor_avg: 0.999957
18562006-09-16 03:36:03.805 AO: audio waiting for buffer to fill: have 3584 want 4096
18572006-09-16 03:36:03.805 AO: Broadcasting free space avail
18582006-09-16 03:36:03.813 AFD: video timecode 367235 367235 4080 4140
18592006-09-16 03:36:03.815 AFD: audio timecode 372606 372606 4140 4164
18602006-09-16 03:36:03.815 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=4140
18612006-09-16 03:36:03.817 AO: Broadcasting free space avail
18622006-09-16 03:36:03.817 AO: Broadcasting free space avail
18632006-09-16 03:36:03.818 AO: Broadcasting free space avail
18642006-09-16 03:36:03.819 AFD: audio timecode 374766 374766 4164 4188
18652006-09-16 03:36:03.820 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=4164
18662006-09-16 03:36:03.828 NVP: A/V Divergence: -1.64733, Rate: -0.2342, Warpfactor: 0.986728, warpfactor_avg: 0.999956
18672006-09-16 03:36:03.829 AO: Broadcasting free space avail
18682006-09-16 03:36:03.829 AO: audio waiting for buffer to fill: have 512 want 4096
18692006-09-16 03:36:03.829 AO: Broadcasting free space avail
18702006-09-16 03:36:03.834 NVP: A/V Divergence: -1.61047, Rate: 0.03685, Warpfactor: 0.987221, warpfactor_avg: 0.999955
18712006-09-16 03:36:03.845 AO: audio waiting for buffer to fill: have 512 want 4096
18722006-09-16 03:36:03.845 AO: Broadcasting free space avail
18732006-09-16 03:36:03.853 AO: audio waiting for buffer to fill: have 512 want 4096
18742006-09-16 03:36:03.853 AO: Broadcasting free space avail
18752006-09-16 03:36:03.857 AFD: video timecode 381635 370835 4120 4188
18762006-09-16 03:36:03.858 AFD: audio timecode 376926 376926 4188 4212
18772006-09-16 03:36:03.859 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=4188
18782006-09-16 03:36:03.860 AFD: audio timecode 379086 379086 4212 4236
18792006-09-16 03:36:03.864 NVP: A/V Divergence: -1.47035, Rate: 0.140125, Warpfactor: 0.987715, warpfactor_avg: 0.999954
18802006-09-16 03:36:03.860 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=4212
18812006-09-16 03:36:03.869 AO: Broadcasting free space avail
18822006-09-16 03:36:03.870 AO: Broadcasting free space avail
18832006-09-16 03:36:03.870 AO: audio waiting for buffer to fill: have 1536 want 4096
18842006-09-16 03:36:03.870 AO: Broadcasting free space avail
18852006-09-16 03:36:03.874 NVP: prebuffering pause
18862006-09-16 03:36:03.874 NVP: Waiting for prebuffer.. 0 ALdAAALAAAAAAAAA
18872006-09-16 03:36:03.881 AO: OutputAudioLoop: audio paused
18882006-09-16 03:36:03.881 AO: 50784 bytes free on soundcard
18892006-09-16 03:36:03.894 AO: 53216 bytes free on soundcard
18902006-09-16 03:36:03.901 AFD: video timecode 374435 374435 4160 4236
18912006-09-16 03:36:03.906 AFD: audio timecode 381246 381246 4236 4260
18922006-09-16 03:36:03.907 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4236
18932006-09-16 03:36:03.909 AO: 56032 bytes free on soundcard
18942006-09-16 03:36:03.925 AO: 59104 bytes free on soundcard
18952006-09-16 03:36:03.933 AO: 60704 bytes free on soundcard
18962006-09-16 03:36:03.941 AFD: video timecode 378035 378035 4200 4260
18972006-09-16 03:36:03.943 AFD: audio timecode 383406 383406 4260 4284
18982006-09-16 03:36:03.943 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=4260
18992006-09-16 03:36:03.946 AO: 63168 bytes free on soundcard
19002006-09-16 03:36:03.949 AFD: audio timecode 385566 385566 4284 4308
19012006-09-16 03:36:03.949 AO: _AddSamples bytes=4608, used=10753, free=757247, timecode=4284
19022006-09-16 03:36:03.955 NVP: A/V Divergence: -1.2465, Rate: 0.22385, Warpfactor: 0.988209, warpfactor_avg: 0.999953
19032006-09-16 03:36:03.956 AO: OutputAudioLoop: Play Event
19042006-09-16 03:36:03.960 AO: Broadcasting free space avail
19052006-09-16 03:36:03.961 AO: Broadcasting free space avail
19062006-09-16 03:36:03.961 AO: Broadcasting free space avail
19072006-09-16 03:36:03.961 AO: audio waiting for buffer to fill: have 3072 want 4096
19082006-09-16 03:36:03.961 AO: Broadcasting free space avail
19092006-09-16 03:36:03.968 NVP: A/V Divergence: -1.26612, Rate: -0.019625, Warpfactor: 0.988703, warpfactor_avg: 0.999953
19102006-09-16 03:36:03.977 AO: audio waiting for buffer to fill: have 3072 want 4096
19112006-09-16 03:36:03.977 AO: Broadcasting free space avail
19122006-09-16 03:36:03.989 AO: audio waiting for buffer to fill: have 3072 want 4096
19132006-09-16 03:36:03.990 AO: Broadcasting free space avail
19142006-09-16 03:36:03.993 AFD: video timecode 392435 381635 4240 4308
19152006-09-16 03:36:03.997 AFD: audio timecode 387726 387726 4308 4332
19162006-09-16 03:36:03.997 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=4308
19172006-09-16 03:36:03.999 AFD: audio timecode 389886 389886 4332 4356
19182006-09-16 03:36:03.999 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=4332
19192006-09-16 03:36:04.001 AO: Broadcasting free space avail
19202006-09-16 03:36:04.001 AO: Broadcasting free space avail
19212006-09-16 03:36:04.002 AO: Broadcasting free space avail
19222006-09-16 03:36:04.002 AO: Broadcasting free space avail
19232006-09-16 03:36:04.007 NVP: A/V Divergence: -1.27457, Rate: -0.00845, Warpfactor: 0.989197, warpfactor_avg: 0.999952
19242006-09-16 03:36:04.013 AO: Broadcasting free space avail
19252006-09-16 03:36:04.022 AO: Broadcasting free space avail
19262006-09-16 03:36:04.029 AFD: video timecode 385235 385235 4280 4356
19272006-09-16 03:36:04.031 AFD: audio timecode 392046 392046 4356 4380
19282006-09-16 03:36:04.031 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=4356
19292006-09-16 03:36:04.033 AO: Broadcasting free space avail
19302006-09-16 03:36:04.034 AO: audio waiting for buffer to fill: have 512 want 4096
19312006-09-16 03:36:04.034 AO: Broadcasting free space avail
19322006-09-16 03:36:04.042 AO: audio waiting for buffer to fill: have 512 want 4096
19332006-09-16 03:36:04.042 AO: Broadcasting free space avail
19342006-09-16 03:36:04.054 AO: audio waiting for buffer to fill: have 512 want 4096
19352006-09-16 03:36:04.054 AO: Broadcasting free space avail
19362006-09-16 03:36:04.063 AO: audio waiting for buffer to fill: have 512 want 4096
19372006-09-16 03:36:04.063 AO: Broadcasting free space avail
19382006-09-16 03:36:04.069 AFD: video timecode 388835 388835 4320 4380
19392006-09-16 03:36:04.073 AO: audio waiting for buffer to fill: have 512 want 4096
19402006-09-16 03:36:04.074 AO: Broadcasting free space avail
19412006-09-16 03:36:04.078 NVP: A/V Divergence: -1.40593, Rate: -0.13135, Warpfactor: 0.988702, warpfactor_avg: 0.999951
19422006-09-16 03:36:04.081 AFD: audio timecode 394205 394205 4380 4404
19432006-09-16 03:36:04.081 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=4380
19442006-09-16 03:36:04.083 AFD: audio timecode 396365 396365 4404 4428
19452006-09-16 03:36:04.083 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=4404
19462006-09-16 03:36:04.086 AO: Broadcasting free space avail
19472006-09-16 03:36:04.086 AO: Broadcasting free space avail
19482006-09-16 03:36:04.092 NVP: A/V Divergence: -1.41692, Rate: -0.011, Warpfactor: 0.988528, warpfactor_avg: 0.99995
19492006-09-16 03:36:04.087 AO: audio waiting for buffer to fill: have 1536 want 4096
19502006-09-16 03:36:04.094 AO: Broadcasting free space avail
19512006-09-16 03:36:04.106 AO: audio waiting for buffer to fill: have 1536 want 4096
19522006-09-16 03:36:04.106 AO: Broadcasting free space avail
19532006-09-16 03:36:04.114 AO: audio waiting for buffer to fill: have 1536 want 4096
19542006-09-16 03:36:04.115 AO: Broadcasting free space avail
19552006-09-16 03:36:04.121 AFD: video timecode 403235 392435 4360 4428
19562006-09-16 03:36:04.123 AFD: audio timecode 398525 398525 4428 4452
19572006-09-16 03:36:04.123 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4428
19582006-09-16 03:36:04.126 AO: Broadcasting free space avail
19592006-09-16 03:36:04.126 AO: audio waiting for buffer to fill: have 2048 want 4096
19602006-09-16 03:36:04.126 AO: Broadcasting free space avail
19612006-09-16 03:36:04.129 AFD: audio timecode 400685 400685 4452 4476
19622006-09-16 03:36:04.130 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=4452
19632006-09-16 03:36:04.136 NVP: A/V Divergence: -1.43767, Rate: -0.02075, Warpfactor: 0.988284, warpfactor_avg: 0.99995
19642006-09-16 03:36:04.136 AO: Broadcasting free space avail
19652006-09-16 03:36:04.137 AO: audio waiting for buffer to fill: have 2560 want 4096
19662006-09-16 03:36:04.137 AO: Broadcasting free space avail
19672006-09-16 03:36:04.149 AO: audio waiting for buffer to fill: have 2560 want 4096
19682006-09-16 03:36:04.150 AO: Broadcasting free space avail
19692006-09-16 03:36:04.157 AO: audio waiting for buffer to fill: have 2560 want 4096
19702006-09-16 03:36:04.157 AO: Broadcasting free space avail
19712006-09-16 03:36:04.157 AFD: video timecode 396035 396035 4400 4476
19722006-09-16 03:36:04.159 AFD: audio timecode 402845 402845 4476 4500
19732006-09-16 03:36:04.159 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=4476
19742006-09-16 03:36:04.166 AO: Broadcasting free space avail
19752006-09-16 03:36:04.166 AO: audio waiting for buffer to fill: have 3072 want 4096
19762006-09-16 03:36:04.166 AO: Broadcasting free space avail
19772006-09-16 03:36:04.170 NVP: A/V Divergence: -1.422, Rate: 0.015675, Warpfactor: 0.9887, warpfactor_avg: 0.999949
19782006-09-16 03:36:04.181 AO: audio waiting for buffer to fill: have 3072 want 4096
19792006-09-16 03:36:04.181 AO: Broadcasting free space avail
19802006-09-16 03:36:04.192 AO: audio waiting for buffer to fill: have 3072 want 4096
19812006-09-16 03:36:04.193 AO: Broadcasting free space avail
19822006-09-16 03:36:04.200 AFD: video timecode 399635 399635 4440 4500
19832006-09-16 03:36:04.202 AFD: audio timecode 405005 405005 4500 4524
19842006-09-16 03:36:04.203 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=4500
19852006-09-16 03:36:04.206 AFD: audio timecode 407165 407165 4524 4548
19862006-09-16 03:36:04.206 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=4524
19872006-09-16 03:36:04.208 AO: Broadcasting free space avail
19882006-09-16 03:36:04.209 AO: Broadcasting free space avail
19892006-09-16 03:36:04.209 AO: Broadcasting free space avail
19902006-09-16 03:36:04.209 AO: Broadcasting free space avail
19912006-09-16 03:36:04.217 NVP: A/V Divergence: -1.43525, Rate: -0.01325, Warpfactor: 0.988361, warpfactor_avg: 0.999948
19922006-09-16 03:36:04.219 AO: Broadcasting free space avail
19932006-09-16 03:36:04.229 AO: Broadcasting free space avail
19942006-09-16 03:36:04.237 AO: Broadcasting free space avail
19952006-09-16 03:36:04.241 AFD: video timecode 414035 403235 4480 4548
19962006-09-16 03:36:04.243 AFD: audio timecode 409325 409325 4548 4572
19972006-09-16 03:36:04.243 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=4548
19982006-09-16 03:36:04.244 AFD: audio timecode 411485 411485 4572 4596
19992006-09-16 03:36:04.245 AO: _AddSamples bytes=4608, used=4609, free=763391, timecode=4572
20002006-09-16 03:36:04.252 AO: Broadcasting free space avail
20012006-09-16 03:36:04.252 AO: Broadcasting free space avail
20022006-09-16 03:36:04.252 AO: audio waiting for buffer to fill: have 1024 want 4096
20032006-09-16 03:36:04.253 AO: Broadcasting free space avail
20042006-09-16 03:36:04.255 NVP: A/V Divergence: -1.36392, Rate: 0.071325, Warpfactor: 0.988856, warpfactor_avg: 0.999947
20052006-09-16 03:36:04.271 AO: audio waiting for buffer to fill: have 1024 want 4096
20062006-09-16 03:36:04.271 AO: Broadcasting free space avail
20072006-09-16 03:36:04.277 AFD: video timecode 406835 406835 4520 4596
20082006-09-16 03:36:04.281 AO: audio waiting for buffer to fill: have 1024 want 4096
20092006-09-16 03:36:04.281 AO: Broadcasting free space avail
20102006-09-16 03:36:04.280 AFD: audio timecode 413645 413645 4596 4620
20112006-09-16 03:36:04.284 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=4596
20122006-09-16 03:36:04.291 NVP: A/V Divergence: -1.37917, Rate: -0.01525, Warpfactor: 0.988792, warpfactor_avg: 0.999947
20132006-09-16 03:36:04.292 AO: Broadcasting free space avail
20142006-09-16 03:36:04.294 AO: audio waiting for buffer to fill: have 1536 want 4096
20152006-09-16 03:36:04.294 AO: Broadcasting free space avail
20162006-09-16 03:36:04.305 AO: audio waiting for buffer to fill: have 1536 want 4096
20172006-09-16 03:36:04.305 AO: Broadcasting free space avail
20182006-09-16 03:36:04.313 AO: audio waiting for buffer to fill: have 1536 want 4096
20192006-09-16 03:36:04.313 AO: Broadcasting free space avail
20202006-09-16 03:36:04.317 AFD: video timecode 410435 410435 4560 4620
20212006-09-16 03:36:04.319 AFD: audio timecode 415805 415805 4620 4644
20222006-09-16 03:36:04.319 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4620
20232006-09-16 03:36:04.320 AFD: audio timecode 417965 417965 4644 4668
20242006-09-16 03:36:04.321 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=4644
20252006-09-16 03:36:04.326 AO: Broadcasting free space avail
20262006-09-16 03:36:04.326 AO: Broadcasting free space avail
20272006-09-16 03:36:04.326 AO: audio waiting for buffer to fill: have 2560 want 4096
20282006-09-16 03:36:04.326 AO: Broadcasting free space avail
20292006-09-16 03:36:04.332 NVP: A/V Divergence: -1.39062, Rate: -0.01145, Warpfactor: 0.988731, warpfactor_avg: 0.999946
20302006-09-16 03:36:04.333 AO: audio waiting for buffer to fill: have 2560 want 4096
20312006-09-16 03:36:04.333 AO: Broadcasting free space avail
20322006-09-16 03:36:04.346 AO: audio waiting for buffer to fill: have 2560 want 4096
20332006-09-16 03:36:04.347 AO: Broadcasting free space avail
20342006-09-16 03:36:04.356 AO: audio waiting for buffer to fill: have 2560 want 4096
20352006-09-16 03:36:04.356 AO: Broadcasting free space avail
20362006-09-16 03:36:04.362 AFD: video timecode 424835 414035 4600 4668
20372006-09-16 03:36:04.370 AFD: audio timecode 420125 420125 4668 4692
20382006-09-16 03:36:04.371 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=4668
20392006-09-16 03:36:04.373 AFD: audio timecode 422285 422285 4692 4716
20402006-09-16 03:36:04.373 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=4692
20412006-09-16 03:36:04.379 AO: Broadcasting free space avail
20422006-09-16 03:36:04.379 AO: Broadcasting free space avail
20432006-09-16 03:36:04.380 AO: audio waiting for buffer to fill: have 3584 want 4096
20442006-09-16 03:36:04.380 AO: Broadcasting free space avail
20452006-09-16 03:36:04.384 NVP: A/V Divergence: -1.4742, Rate: -0.083575, Warpfactor: 0.988236, warpfactor_avg: 0.999945
20462006-09-16 03:36:04.386 AO: audio waiting for buffer to fill: have 3584 want 4096
20472006-09-16 03:36:04.386 AO: Broadcasting free space avail
20482006-09-16 03:36:04.387 NVP: prebuffering pause
20492006-09-16 03:36:04.387 NVP: Waiting for prebuffer.. 0 LAAAAAAAAAAAALdA
20502006-09-16 03:36:04.397 AO: OutputAudioLoop: audio paused
20512006-09-16 03:36:04.398 AO: 55680 bytes free on soundcard
20522006-09-16 03:36:04.405 AO: 57184 bytes free on soundcard
20532006-09-16 03:36:04.409 AFD: video timecode 417635 417635 4640 4716
20542006-09-16 03:36:04.415 AFD: audio timecode 424445 424445 4716 4740
20552006-09-16 03:36:04.415 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=4716
20562006-09-16 03:36:04.421 AO: 60192 bytes free on soundcard
20572006-09-16 03:36:04.434 AO: 62688 bytes free on soundcard
20582006-09-16 03:36:04.441 AFD: video timecode 421235 421235 4680 4740
20592006-09-16 03:36:04.444 AFD: audio timecode 426605 426605 4740 4764
20602006-09-16 03:36:04.445 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=4740
20612006-09-16 03:36:04.449 AO: 61472 bytes free on soundcard
20622006-09-16 03:36:04.449 AFD: audio timecode 428765 428765 4764 4788
20632006-09-16 03:36:04.456 AO: _AddSamples bytes=4608, used=12801, free=755199, timecode=4764
20642006-09-16 03:36:04.459 NVP: A/V Divergence: -1.3244, Rate: 0.1498, Warpfactor: 0.98873, warpfactor_avg: 0.999944
20652006-09-16 03:36:04.463 AO: OutputAudioLoop: Play Event
20662006-09-16 03:36:04.464 AO: Broadcasting free space avail
20672006-09-16 03:36:04.464 AO: Broadcasting free space avail
20682006-09-16 03:36:04.464 AO: Broadcasting free space avail
20692006-09-16 03:36:04.464 AO: Broadcasting free space avail
20702006-09-16 03:36:04.465 AO: audio waiting for buffer to fill: have 1024 want 4096
20712006-09-16 03:36:04.465 AO: Broadcasting free space avail
20722006-09-16 03:36:04.473 NVP: A/V Divergence: -1.21205, Rate: 0.11235, Warpfactor: 0.989225, warpfactor_avg: 0.999944
20732006-09-16 03:36:04.474 AO: audio waiting for buffer to fill: have 1024 want 4096
20742006-09-16 03:36:04.474 AO: Broadcasting free space avail
20752006-09-16 03:36:04.488 AO: audio waiting for buffer to fill: have 1024 want 4096
20762006-09-16 03:36:04.489 AO: Broadcasting free space avail
20772006-09-16 03:36:04.502 AFD: video timecode 435635 424835 4720 4788
20782006-09-16 03:36:04.503 AFD: audio timecode 430925 430925 4788 4812
20792006-09-16 03:36:04.504 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=4788
20802006-09-16 03:36:04.505 AO: Broadcasting free space avail
20812006-09-16 03:36:04.505 AO: audio waiting for buffer to fill: have 1536 want 4096
20822006-09-16 03:36:04.505 AO: Broadcasting free space avail
20832006-09-16 03:36:04.510 AFD: audio timecode 433085 433085 4812 4836
20842006-09-16 03:36:04.510 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=4812
20852006-09-16 03:36:04.519 NVP: A/V Divergence: -1.15278, Rate: 0.059275, Warpfactor: 0.989719, warpfactor_avg: 0.999943
20862006-09-16 03:36:04.519 AO: Broadcasting free space avail
20872006-09-16 03:36:04.520 AO: audio waiting for buffer to fill: have 2048 want 4096
20882006-09-16 03:36:04.520 AO: Broadcasting free space avail
20892006-09-16 03:36:04.529 AO: audio waiting for buffer to fill: have 2048 want 4096
20902006-09-16 03:36:04.532 AO: Broadcasting free space avail
20912006-09-16 03:36:04.540 AO: audio waiting for buffer to fill: have 2048 want 4096
20922006-09-16 03:36:04.541 AO: Broadcasting free space avail
20932006-09-16 03:36:04.546 AFD: video timecode 428435 428435 4760 4836
20942006-09-16 03:36:04.547 AFD: audio timecode 435245 435245 4836 4860
20952006-09-16 03:36:04.550 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=4836
20962006-09-16 03:36:04.555 AO: Broadcasting free space avail
20972006-09-16 03:36:04.555 AO: audio waiting for buffer to fill: have 2560 want 4096
20982006-09-16 03:36:04.555 AO: Broadcasting free space avail
20992006-09-16 03:36:04.560 NVP: A/V Divergence: -1.12083, Rate: 0.03195, Warpfactor: 0.990214, warpfactor_avg: 0.999942
21002006-09-16 03:36:04.562 AO: audio waiting for buffer to fill: have 2560 want 4096
21012006-09-16 03:36:04.562 AO: Broadcasting free space avail
21022006-09-16 03:36:04.562 NVP: prebuffering pause
21032006-09-16 03:36:04.563 NVP: Waiting for prebuffer.. 0 dLDLAAAAAAAAAAAA
21042006-09-16 03:36:04.575 AO: OutputAudioLoop: audio paused
21052006-09-16 03:36:04.576 AO: 53024 bytes free on soundcard
21062006-09-16 03:36:04.581 AO: 54144 bytes free on soundcard
21072006-09-16 03:36:04.589 AFD: video timecode 432035 432035 4800 4860
21082006-09-16 03:36:04.591 AFD: audio timecode 437405 437405 4860 4884
21092006-09-16 03:36:04.591 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=4860
21102006-09-16 03:36:04.594 AFD: audio timecode 439565 439565 4884 4908
21112006-09-16 03:36:04.594 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=4884
21122006-09-16 03:36:04.597 AO: 57088 bytes free on soundcard
21132006-09-16 03:36:04.611 AO: 59776 bytes free on soundcard
21142006-09-16 03:36:04.617 AFD: video timecode 446435 435635 4840 4908
21152006-09-16 03:36:04.619 AFD: audio timecode 441725 441725 4908 4932
21162006-09-16 03:36:04.619 AO: _AddSamples bytes=4608, used=11777, free=756223, timecode=4908
21172006-09-16 03:36:04.620 AFD: audio timecode 443885 443885 4932 4956
21182006-09-16 03:36:04.621 AO: _AddSamples bytes=4608, used=16385, free=751615, timecode=4932
21192006-09-16 03:36:04.623 AO: 62048 bytes free on soundcard
21202006-09-16 03:36:04.627 NVP: A/V Divergence: -0.8906, Rate: 0.230225, Warpfactor: 0.990709, warpfactor_avg: 0.999942
21212006-09-16 03:36:04.637 AO: OutputAudioLoop: Play Event
21222006-09-16 03:36:04.637 AO: Broadcasting free space avail
21232006-09-16 03:36:04.638 AO: Broadcasting free space avail
21242006-09-16 03:36:04.638 AO: Broadcasting free space avail
21252006-09-16 03:36:04.638 AO: Broadcasting free space avail
21262006-09-16 03:36:04.639 AO: Broadcasting free space avail
21272006-09-16 03:36:04.639 AO: audio waiting for buffer to fill: have 512 want 4096
21282006-09-16 03:36:04.639 AO: Broadcasting free space avail
21292006-09-16 03:36:04.645 AO: audio waiting for buffer to fill: have 512 want 4096
21302006-09-16 03:36:04.645 AO: Broadcasting free space avail
21312006-09-16 03:36:04.650 AFD: video timecode 439235 439235 4880 4956
21322006-09-16 03:36:04.658 AFD: audio timecode 446045 446045 4956 4980
21332006-09-16 03:36:04.659 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=4956
21342006-09-16 03:36:04.661 NVP: A/V Divergence: -0.8492, Rate: 0.0414, Warpfactor: 0.991204, warpfactor_avg: 0.999941
21352006-09-16 03:36:04.661 AO: Broadcasting free space avail
21362006-09-16 03:36:04.665 AO: audio waiting for buffer to fill: have 1024 want 4096
21372006-09-16 03:36:04.666 AO: Broadcasting free space avail
21382006-09-16 03:36:04.673 NVP: A/V Divergence: -0.7244, Rate: 0.1248, Warpfactor: 0.9917, warpfactor_avg: 0.999941
21392006-09-16 03:36:04.683 AO: audio waiting for buffer to fill: have 1024 want 4096
21402006-09-16 03:36:04.683 AO: Broadcasting free space avail
21412006-09-16 03:36:04.698 AO: audio waiting for buffer to fill: have 1024 want 4096
21422006-09-16 03:36:04.699 AO: Broadcasting free space avail
21432006-09-16 03:36:04.705 AFD: video timecode 442835 442835 4920 4980
21442006-09-16 03:36:04.707 AFD: audio timecode 448205 448205 4980 5004
21452006-09-16 03:36:04.707 AO: audio waiting for buffer to fill: have 1024 want 4096
21462006-09-16 03:36:04.707 AO: Broadcasting free space avail
21472006-09-16 03:36:04.708 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=4980
21482006-09-16 03:36:04.710 AFD: audio timecode 450365 450365 5004 5028
21492006-09-16 03:36:04.711 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=5004
21502006-09-16 03:36:04.714 AO: Broadcasting free space avail
21512006-09-16 03:36:04.717 AO: Broadcasting free space avail
21522006-09-16 03:36:04.717 AO: audio waiting for buffer to fill: have 2048 want 4096
21532006-09-16 03:36:04.718 AO: Broadcasting free space avail
2154'video_output' mean = '46769.25', std. dev. = '23883.35', fps = '21.38'
21552006-09-16 03:36:04.719 A/V avsync_delay: -14, avsync_avg: -28, warpfactor: 0.9917, warpfactor_avg: 0.999941
21562006-09-16 03:36:04.726 NVP: A/V Divergence: -0.6933, Rate: 0.0311, Warpfactor: 0.992196, warpfactor_avg: 0.99994
21572006-09-16 03:36:04.727 AO: audio waiting for buffer to fill: have 2048 want 4096
21582006-09-16 03:36:04.727 AO: Broadcasting free space avail
21592006-09-16 03:36:04.741 AO: audio waiting for buffer to fill: have 2048 want 4096
21602006-09-16 03:36:04.741 AO: Broadcasting free space avail
21612006-09-16 03:36:04.749 AO: audio waiting for buffer to fill: have 2048 want 4096
21622006-09-16 03:36:04.750 AO: Broadcasting free space avail
21632006-09-16 03:36:04.753 AFD: video timecode 457234 446435 4960 5028
21642006-09-16 03:36:04.756 AFD: audio timecode 452525 452525 5028 5052
21652006-09-16 03:36:04.759 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5028
21662006-09-16 03:36:04.761 AFD: audio timecode 454685 454685 5052 5076
21672006-09-16 03:36:04.761 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=5052
21682006-09-16 03:36:04.764 AO: Broadcasting free space avail
21692006-09-16 03:36:04.764 AO: Broadcasting free space avail
21702006-09-16 03:36:04.765 AO: audio waiting for buffer to fill: have 3072 want 4096
21712006-09-16 03:36:04.765 AO: Broadcasting free space avail
21722006-09-16 03:36:04.772 NVP: A/V Divergence: -0.713725, Rate: -0.020425, Warpfactor: 0.992692, warpfactor_avg: 0.99994
21732006-09-16 03:36:04.781 AO: audio waiting for buffer to fill: have 3072 want 4096
21742006-09-16 03:36:04.790 AO: Broadcasting free space avail
21752006-09-16 03:36:04.794 AFD: video timecode 450035 450035 5000 5076
21762006-09-16 03:36:04.796 AFD: audio timecode 456845 456845 5076 5100
21772006-09-16 03:36:04.796 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=5076
21782006-09-16 03:36:04.799 AO: Broadcasting free space avail
21792006-09-16 03:36:04.799 AO: audio waiting for buffer to fill: have 3584 want 4096
21802006-09-16 03:36:04.799 AO: Broadcasting free space avail
21812006-09-16 03:36:04.806 NVP: A/V Divergence: -0.685275, Rate: 0.02845, Warpfactor: 0.993188, warpfactor_avg: 0.999939
21822006-09-16 03:36:04.808 AO: audio waiting for buffer to fill: have 3584 want 4096
21832006-09-16 03:36:04.808 AO: Broadcasting free space avail
21842006-09-16 03:36:04.821 AO: audio waiting for buffer to fill: have 3584 want 4096
21852006-09-16 03:36:04.821 AO: Broadcasting free space avail
21862006-09-16 03:36:04.829 AO: audio waiting for buffer to fill: have 3584 want 4096
21872006-09-16 03:36:04.829 AO: Broadcasting free space avail
21882006-09-16 03:36:04.833 AFD: video timecode 453635 453635 5040 5100
21892006-09-16 03:36:04.838 AFD: audio timecode 459005 459005 5100 5124
21902006-09-16 03:36:04.839 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=5100
21912006-09-16 03:36:04.844 AO: Broadcasting free space avail
21922006-09-16 03:36:04.844 AO: Broadcasting free space avail
21932006-09-16 03:36:04.845 AO: Broadcasting free space avail
21942006-09-16 03:36:04.840 AFD: audio timecode 461165 461165 5124 5148
21952006-09-16 03:36:04.846 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=5124
21962006-09-16 03:36:04.858 NVP: A/V Divergence: -0.73895, Rate: -0.053675, Warpfactor: 0.993598, warpfactor_avg: 0.999939
21972006-09-16 03:36:04.858 AO: Broadcasting free space avail
21982006-09-16 03:36:04.859 AO: audio waiting for buffer to fill: have 512 want 4096
21992006-09-16 03:36:04.859 AO: Broadcasting free space avail
22002006-09-16 03:36:04.863 NVP: prebuffering pause
22012006-09-16 03:36:04.864 NVP: Waiting for prebuffer.. 0 AAAADAALADLAAAAA
22022006-09-16 03:36:04.873 AO: OutputAudioLoop: audio paused
22032006-09-16 03:36:04.873 AO: 48768 bytes free on soundcard
22042006-09-16 03:36:04.883 AO: 50656 bytes free on soundcard
22052006-09-16 03:36:04.890 AFD: video timecode 468034 457234 5080 5148
22062006-09-16 03:36:04.891 AFD: audio timecode 463325 463325 5148 5172
22072006-09-16 03:36:04.892 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5148
22082006-09-16 03:36:04.896 AFD: audio timecode 465484 465484 5172 5196
22092006-09-16 03:36:04.896 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=5172
22102006-09-16 03:36:04.897 AO: 53248 bytes free on soundcard
22112006-09-16 03:36:04.913 AO: 56352 bytes free on soundcard
22122006-09-16 03:36:04.924 AO: 58528 bytes free on soundcard
22132006-09-16 03:36:04.929 AFD: video timecode 460834 460834 5120 5196
22142006-09-16 03:36:04.930 AFD: audio timecode 467644 467644 5196 5220
22152006-09-16 03:36:04.931 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=5196
22162006-09-16 03:36:04.933 NVP: A/V Divergence: -0.6042, Rate: 0.13475, Warpfactor: 0.994095, warpfactor_avg: 0.999938
22172006-09-16 03:36:04.933 AO: OutputAudioLoop: Play Event
22182006-09-16 03:36:04.934 AO: Broadcasting free space avail
22192006-09-16 03:36:04.934 AO: Broadcasting free space avail
22202006-09-16 03:36:04.934 AO: Broadcasting free space avail
22212006-09-16 03:36:04.935 AO: audio waiting for buffer to fill: have 2048 want 4096
22222006-09-16 03:36:04.935 AO: Broadcasting free space avail
22232006-09-16 03:36:04.949 AO: audio waiting for buffer to fill: have 2048 want 4096
22242006-09-16 03:36:04.949 AO: Broadcasting free space avail
22252006-09-16 03:36:04.959 AO: audio waiting for buffer to fill: have 2048 want 4096
22262006-09-16 03:36:04.959 AO: Broadcasting free space avail
22272006-09-16 03:36:04.963 AFD: video timecode 464434 464434 5160 5220
22282006-09-16 03:36:04.965 AFD: audio timecode 469804 469804 5220 5244
22292006-09-16 03:36:04.966 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5220
22302006-09-16 03:36:04.969 AFD: audio timecode 471964 471964 5244 5268
22312006-09-16 03:36:04.970 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=5244
22322006-09-16 03:36:04.973 AO: Broadcasting free space avail
22332006-09-16 03:36:04.973 AO: Broadcasting free space avail
22342006-09-16 03:36:04.974 AO: audio waiting for buffer to fill: have 3072 want 4096
22352006-09-16 03:36:04.974 AO: Broadcasting free space avail
22362006-09-16 03:36:04.993 AO: audio waiting for buffer to fill: have 3072 want 4096
22372006-09-16 03:36:04.993 AO: Broadcasting free space avail
22382006-09-16 03:36:05.001 AO: audio waiting for buffer to fill: have 3072 want 4096
22392006-09-16 03:36:05.001 AO: Broadcasting free space avail
22402006-09-16 03:36:05.006 AFD: video timecode 478834 468034 5200 5268
22412006-09-16 03:36:05.008 AFD: audio timecode 474124 474124 5268 5292
22422006-09-16 03:36:05.010 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=5268
22432006-09-16 03:36:05.012 AFD: audio timecode 476284 476284 5292 5316
22442006-09-16 03:36:05.012 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=5292
22452006-09-16 03:36:05.016 NVP: A/V Divergence: -1.09065, Rate: -0.48645, Warpfactor: 0.993598, warpfactor_avg: 0.999938
22462006-09-16 03:36:05.019 AO: Broadcasting free space avail
22472006-09-16 03:36:05.019 AO: Broadcasting free space avail
22482006-09-16 03:36:05.020 AO: Broadcasting free space avail
22492006-09-16 03:36:05.020 AO: Broadcasting free space avail
22502006-09-16 03:36:05.029 NVP: A/V Divergence: -1.36172, Rate: -0.271075, Warpfactor: 0.993101, warpfactor_avg: 0.999937
22512006-09-16 03:36:05.031 AO: Broadcasting free space avail
22522006-09-16 03:36:05.041 AO: Broadcasting free space avail
22532006-09-16 03:36:05.049 AO: Broadcasting free space avail
22542006-09-16 03:36:05.053 AFD: video timecode 471634 471634 5240 5316
22552006-09-16 03:36:05.056 AFD: audio timecode 478444 478444 5316 5340
22562006-09-16 03:36:05.057 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=5316
22572006-09-16 03:36:05.069 AO: Broadcasting free space avail
22582006-09-16 03:36:05.070 AO: audio waiting for buffer to fill: have 512 want 4096
22592006-09-16 03:36:05.070 AO: Broadcasting free space avail
22602006-09-16 03:36:05.060 NVP: A/V Divergence: -1.45878, Rate: -0.09705, Warpfactor: 0.992605, warpfactor_avg: 0.999937
22612006-09-16 03:36:05.077 AO: audio waiting for buffer to fill: have 512 want 4096
22622006-09-16 03:36:05.078 AO: Broadcasting free space avail
22632006-09-16 03:36:05.091 AFD: video timecode 475234 475234 5280 5340
22642006-09-16 03:36:05.093 AFD: audio timecode 480604 480604 5340 5364
22652006-09-16 03:36:05.093 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5340
22662006-09-16 03:36:05.095 AFD: audio timecode 482764 482764 5364 5388
22672006-09-16 03:36:05.095 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=5364
22682006-09-16 03:36:05.097 AO: Broadcasting free space avail
22692006-09-16 03:36:05.097 AO: Broadcasting free space avail
22702006-09-16 03:36:05.097 AO: audio waiting for buffer to fill: have 1536 want 4096
22712006-09-16 03:36:05.098 AO: Broadcasting free space avail
22722006-09-16 03:36:05.109 NVP: A/V Divergence: -1.62532, Rate: -0.16655, Warpfactor: 0.992108, warpfactor_avg: 0.999936
22732006-09-16 03:36:05.110 AO: audio waiting for buffer to fill: have 1536 want 4096
22742006-09-16 03:36:05.110 AO: Broadcasting free space avail
22752006-09-16 03:36:05.125 AO: audio waiting for buffer to fill: have 1536 want 4096
22762006-09-16 03:36:05.125 AO: Broadcasting free space avail
22772006-09-16 03:36:05.133 AO: audio waiting for buffer to fill: have 1536 want 4096
22782006-09-16 03:36:05.133 AO: Broadcasting free space avail
22792006-09-16 03:36:05.140 AFD: video timecode 489634 478834 5320 5388
22802006-09-16 03:36:05.147 AFD: audio timecode 484924 484924 5388 5412
22812006-09-16 03:36:05.148 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=5388
22822006-09-16 03:36:05.149 AFD: audio timecode 487084 487084 5412 5436
22832006-09-16 03:36:05.150 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=5412
22842006-09-16 03:36:05.151 AO: Broadcasting free space avail
22852006-09-16 03:36:05.152 AO: Broadcasting free space avail
22862006-09-16 03:36:05.152 AO: audio waiting for buffer to fill: have 2560 want 4096
22872006-09-16 03:36:05.152 AO: Broadcasting free space avail
22882006-09-16 03:36:05.153 NVP: A/V Divergence: -1.68148, Rate: -0.05615, Warpfactor: 0.991612, warpfactor_avg: 0.999936
22892006-09-16 03:36:05.159 AO: audio waiting for buffer to fill: have 2560 want 4096
22902006-09-16 03:36:05.159 AO: Broadcasting free space avail
22912006-09-16 03:36:05.163 NVP: A/V Divergence: -1.6611, Rate: 0.020375, Warpfactor: 0.991116, warpfactor_avg: 0.999935
22922006-09-16 03:36:05.177 AO: audio waiting for buffer to fill: have 2560 want 4096
22932006-09-16 03:36:05.178 AO: Broadcasting free space avail
22942006-09-16 03:36:05.186 AO: audio waiting for buffer to fill: have 2560 want 4096
22952006-09-16 03:36:05.187 AO: Broadcasting free space avail
22962006-09-16 03:36:05.192 AFD: video timecode 482434 482434 5360 5436
22972006-09-16 03:36:05.196 NVP: A/V Divergence: -1.52708, Rate: 0.134025, Warpfactor: 0.990621, warpfactor_avg: 0.999935
22982006-09-16 03:36:05.198 AFD: audio timecode 489244 489244 5436 5460
22992006-09-16 03:36:05.199 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=5436
23002006-09-16 03:36:05.201 AO: Broadcasting free space avail
23012006-09-16 03:36:05.201 AO: audio waiting for buffer to fill: have 3072 want 4096
23022006-09-16 03:36:05.201 AO: Broadcasting free space avail
23032006-09-16 03:36:05.202 NVP: prebuffering pause
23042006-09-16 03:36:05.203 NVP: Waiting for prebuffer.. 0 LALDAAAAAAAAAdAA
23052006-09-16 03:36:05.217 AO: OutputAudioLoop: audio paused
23062006-09-16 03:36:05.217 AO: 57472 bytes free on soundcard
23072006-09-16 03:36:05.227 AO: 59360 bytes free on soundcard
23082006-09-16 03:36:05.228 AFD: video timecode 486034 486034 5400 5460
23092006-09-16 03:36:05.231 AFD: audio timecode 491404 491404 5460 5484
23102006-09-16 03:36:05.231 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=5460
23112006-09-16 03:36:05.233 AFD: audio timecode 493564 493564 5484 5508
23122006-09-16 03:36:05.235 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=5484
23132006-09-16 03:36:05.242 AO: 62208 bytes free on soundcard
23142006-09-16 03:36:05.253 AO: 60256 bytes free on soundcard
23152006-09-16 03:36:05.263 AO: 62112 bytes free on soundcard
23162006-09-16 03:36:05.263 AFD: video timecode 500434 489634 5440 5508
23172006-09-16 03:36:05.265 AFD: audio timecode 495724 495724 5508 5532
23182006-09-16 03:36:05.265 AO: _AddSamples bytes=4608, used=12289, free=755711, timecode=5508
23192006-09-16 03:36:05.268 AFD: audio timecode 497884 497884 5532 5556
23202006-09-16 03:36:05.272 NVP: A/V Divergence: -1.3078, Rate: 0.219275, Warpfactor: 0.991116, warpfactor_avg: 0.999934
23212006-09-16 03:36:05.273 AO: _AddSamples bytes=4608, used=16897, free=751103, timecode=5532
23222006-09-16 03:36:05.273 AO: OutputAudioLoop: Play Event
23232006-09-16 03:36:05.275 AO: Broadcasting free space avail
23242006-09-16 03:36:05.275 AO: Broadcasting free space avail
23252006-09-16 03:36:05.276 AO: Broadcasting free space avail
23262006-09-16 03:36:05.276 AO: Broadcasting free space avail
23272006-09-16 03:36:05.276 AO: Broadcasting free space avail
23282006-09-16 03:36:05.276 AO: audio waiting for buffer to fill: have 1024 want 4096
23292006-09-16 03:36:05.277 AO: Broadcasting free space avail
23302006-09-16 03:36:05.283 NVP: A/V Divergence: -1.1121, Rate: 0.1957, Warpfactor: 0.991612, warpfactor_avg: 0.999933
23312006-09-16 03:36:05.289 AO: audio waiting for buffer to fill: have 1024 want 4096
23322006-09-16 03:36:05.289 AO: Broadcasting free space avail
23332006-09-16 03:36:05.298 AO: audio waiting for buffer to fill: have 1024 want 4096
23342006-09-16 03:36:05.298 AO: Broadcasting free space avail
23352006-09-16 03:36:05.301 AFD: video timecode 493234 493234 5480 5556
23362006-09-16 03:36:05.303 AFD: audio timecode 500044 500044 5556 5580
23372006-09-16 03:36:05.303 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=5556
23382006-09-16 03:36:05.311 AO: Broadcasting free space avail
23392006-09-16 03:36:05.311 AO: audio waiting for buffer to fill: have 1536 want 4096
23402006-09-16 03:36:05.311 AO: Broadcasting free space avail
23412006-09-16 03:36:05.317 NVP: A/V Divergence: -0.934075, Rate: 0.178025, Warpfactor: 0.992107, warpfactor_avg: 0.999933
23422006-09-16 03:36:05.325 AO: audio waiting for buffer to fill: have 1536 want 4096
23432006-09-16 03:36:05.326 AO: Broadcasting free space avail
23442006-09-16 03:36:05.339 AO: audio waiting for buffer to fill: have 1536 want 4096
23452006-09-16 03:36:05.340 AO: Broadcasting free space avail
23462006-09-16 03:36:05.346 AFD: video timecode 496834 496834 5520 5580
23472006-09-16 03:36:05.348 AFD: audio timecode 502204 502204 5580 5604
23482006-09-16 03:36:05.348 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=5580
23492006-09-16 03:36:05.351 AFD: audio timecode 504364 504364 5604 5628
23502006-09-16 03:36:05.352 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=5604
23512006-09-16 03:36:05.352 AFD: positionMap[ 9 ] == 4259878.
23522006-09-16 03:36:05.353 AO: Broadcasting free space avail
23532006-09-16 03:36:05.354 AO: Broadcasting free space avail
23542006-09-16 03:36:05.354 AO: audio waiting for buffer to fill: have 2560 want 4096
23552006-09-16 03:36:05.354 AO: Broadcasting free space avail
23562006-09-16 03:36:05.370 AO: audio waiting for buffer to fill: have 2560 want 4096
23572006-09-16 03:36:05.370 AO: Broadcasting free space avail
23582006-09-16 03:36:05.378 AO: audio waiting for buffer to fill: have 2560 want 4096
23592006-09-16 03:36:05.379 AO: Broadcasting free space avail
23602006-09-16 03:36:05.381 AFD: video timecode 511234 500434 5560 5628
23612006-09-16 03:36:05.382 AFD: audio timecode 506524 506524 5628 5652
23622006-09-16 03:36:05.383 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=5628
23632006-09-16 03:36:05.384 AFD: audio timecode 508684 508684 5652 5676
23642006-09-16 03:36:05.385 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=5652
23652006-09-16 03:36:05.390 AO: Broadcasting free space avail
23662006-09-16 03:36:05.391 AO: Broadcasting free space avail
23672006-09-16 03:36:05.391 AO: audio waiting for buffer to fill: have 3584 want 4096
23682006-09-16 03:36:05.391 AO: Broadcasting free space avail
23692006-09-16 03:36:05.395 NVP: A/V Divergence: -1.03805, Rate: -0.103975, Warpfactor: 0.991611, warpfactor_avg: 0.999932
23702006-09-16 03:36:05.397 AO: audio waiting for buffer to fill: have 3584 want 4096
23712006-09-16 03:36:05.398 AO: Broadcasting free space avail
23722006-09-16 03:36:05.408 NVP: A/V Divergence: -0.934775, Rate: 0.103275, Warpfactor: 0.992107, warpfactor_avg: 0.999932
23732006-09-16 03:36:05.409 AO: audio waiting for buffer to fill: have 3584 want 4096
23742006-09-16 03:36:05.410 AO: Broadcasting free space avail
23752006-09-16 03:36:05.421 AO: audio waiting for buffer to fill: have 3584 want 4096
23762006-09-16 03:36:05.421 AO: Broadcasting free space avail
23772006-09-16 03:36:05.429 AO: audio waiting for buffer to fill: have 3584 want 4096
23782006-09-16 03:36:05.429 AO: Broadcasting free space avail
23792006-09-16 03:36:05.430 AFD: video timecode 504034 504034 5600 5676
23802006-09-16 03:36:05.433 AFD: audio timecode 510844 510844 5676 5700
23812006-09-16 03:36:05.433 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=5676
23822006-09-16 03:36:05.440 NVP: A/V Divergence: -0.807325, Rate: 0.12745, Warpfactor: 0.992603, warpfactor_avg: 0.999931
23832006-09-16 03:36:05.440 AO: Broadcasting free space avail
23842006-09-16 03:36:05.442 AO: Broadcasting free space avail
23852006-09-16 03:36:05.443 AO: Broadcasting free space avail
23862006-09-16 03:36:05.450 AO: Broadcasting free space avail
23872006-09-16 03:36:05.462 AO: Broadcasting free space avail
23882006-09-16 03:36:05.469 AO: Broadcasting free space avail
23892006-09-16 03:36:05.481 AFD: video timecode 507634 507634 5640 5700
23902006-09-16 03:36:05.483 AFD: audio timecode 513004 513004 5700 5724
23912006-09-16 03:36:05.483 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=5700
23922006-09-16 03:36:05.485 AO: Broadcasting free space avail
23932006-09-16 03:36:05.486 AO: audio waiting for buffer to fill: have 512 want 4096
23942006-09-16 03:36:05.486 AO: Broadcasting free space avail
23952006-09-16 03:36:05.485 AFD: audio timecode 515164 515164 5724 5748
23962006-09-16 03:36:05.499 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5724
23972006-09-16 03:36:05.501 AO: Broadcasting free space avail
23982006-09-16 03:36:05.502 AO: audio waiting for buffer to fill: have 1024 want 4096
23992006-09-16 03:36:05.502 AO: Broadcasting free space avail
24002006-09-16 03:36:05.506 NVP: A/V Divergence: -0.886725, Rate: -0.0794, Warpfactor: 0.992203, warpfactor_avg: 0.999931
24012006-09-16 03:36:05.517 AO: audio waiting for buffer to fill: have 1024 want 4096
24022006-09-16 03:36:05.517 AO: Broadcasting free space avail
24032006-09-16 03:36:05.525 AO: audio waiting for buffer to fill: have 1024 want 4096
24042006-09-16 03:36:05.525 AO: Broadcasting free space avail
24052006-09-16 03:36:05.532 AFD: video timecode 522034 511234 5680 5748
24062006-09-16 03:36:05.539 AFD: audio timecode 517324 517324 5748 5772
24072006-09-16 03:36:05.540 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=5748
24082006-09-16 03:36:05.541 AFD: audio timecode 519484 519484 5772 5796
24092006-09-16 03:36:05.542 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=5772
24102006-09-16 03:36:05.549 AO: Broadcasting free space avail
24112006-09-16 03:36:05.549 AO: Broadcasting free space avail
24122006-09-16 03:36:05.550 AO: audio waiting for buffer to fill: have 2048 want 4096
24132006-09-16 03:36:05.550 AO: Broadcasting free space avail
24142006-09-16 03:36:05.536 NVP: A/V Divergence: -0.833775, Rate: 0.05295, Warpfactor: 0.992699, warpfactor_avg: 0.99993
24152006-09-16 03:36:05.554 NVP: prebuffering pause
24162006-09-16 03:36:05.555 NVP: Waiting for prebuffer.. 0 AAAAAAALdAALAAAA
24172006-09-16 03:36:05.558 AO: OutputAudioLoop: audio paused
24182006-09-16 03:36:05.559 AO: 49312 bytes free on soundcard
24192006-09-16 03:36:05.568 AO: 51008 bytes free on soundcard
24202006-09-16 03:36:05.573 AO: 52032 bytes free on soundcard
24212006-09-16 03:36:05.577 AFD: video timecode 514834 514834 5720 5796
24222006-09-16 03:36:05.579 AFD: audio timecode 521644 521644 5796 5820
24232006-09-16 03:36:05.579 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5796
24242006-09-16 03:36:05.586 AO: 54624 bytes free on soundcard
24252006-09-16 03:36:05.603 AO: 57792 bytes free on soundcard
24262006-09-16 03:36:05.609 AFD: video timecode 518434 518434 5760 5820
24272006-09-16 03:36:05.612 AFD: audio timecode 523804 523804 5820 5844
24282006-09-16 03:36:05.612 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=5820
24292006-09-16 03:36:05.613 AO: 59680 bytes free on soundcard
24302006-09-16 03:36:05.619 NVP: A/V Divergence: -0.725325, Rate: 0.10845, Warpfactor: 0.993195, warpfactor_avg: 0.99993
24312006-09-16 03:36:05.620 AFD: audio timecode 525964 525964 5844 5868
24322006-09-16 03:36:05.621 AO: _AddSamples bytes=4608, used=11265, free=756735, timecode=5844
24332006-09-16 03:36:05.626 AO: OutputAudioLoop: Play Event
24342006-09-16 03:36:05.627 AO: Broadcasting free space avail
24352006-09-16 03:36:05.627 AO: Broadcasting free space avail
24362006-09-16 03:36:05.628 AO: Broadcasting free space avail
24372006-09-16 03:36:05.628 AO: audio waiting for buffer to fill: have 3584 want 4096
24382006-09-16 03:36:05.628 AO: Broadcasting free space avail
24392006-09-16 03:36:05.632 NVP: A/V Divergence: -0.856475, Rate: -0.13115, Warpfactor: 0.992699, warpfactor_avg: 0.999929
24402006-09-16 03:36:05.641 AO: audio waiting for buffer to fill: have 3584 want 4096
24412006-09-16 03:36:05.641 AO: Broadcasting free space avail
24422006-09-16 03:36:05.650 AO: audio waiting for buffer to fill: have 3584 want 4096
24432006-09-16 03:36:05.650 AO: Broadcasting free space avail
24442006-09-16 03:36:05.653 AFD: video timecode 532834 522034 5800 5868
24452006-09-16 03:36:05.655 AFD: audio timecode 528124 528124 5868 5892
24462006-09-16 03:36:05.655 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=5868
24472006-09-16 03:36:05.660 AFD: audio timecode 530283 530283 5892 5916
24482006-09-16 03:36:05.660 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=5892
24492006-09-16 03:36:05.662 AO: Broadcasting free space avail
24502006-09-16 03:36:05.663 AO: Broadcasting free space avail
24512006-09-16 03:36:05.663 AO: Broadcasting free space avail
24522006-09-16 03:36:05.663 AO: audio waiting for buffer to fill: have 512 want 4096
24532006-09-16 03:36:05.663 AO: Broadcasting free space avail
24542006-09-16 03:36:05.668 NVP: A/V Divergence: -0.9361, Rate: -0.079625, Warpfactor: 0.992202, warpfactor_avg: 0.999929
24552006-09-16 03:36:05.675 AO: audio waiting for buffer to fill: have 512 want 4096
24562006-09-16 03:36:05.675 AO: Broadcasting free space avail
24572006-09-16 03:36:05.687 AO: audio waiting for buffer to fill: have 512 want 4096
24582006-09-16 03:36:05.687 AO: Broadcasting free space avail
24592006-09-16 03:36:05.693 AO: audio waiting for buffer to fill: have 512 want 4096
24602006-09-16 03:36:05.694 AO: Broadcasting free space avail
24612006-09-16 03:36:05.694 AFD: video timecode 525634 525634 5840 5916
24622006-09-16 03:36:05.696 AFD: audio timecode 532443 532443 5916 5940
24632006-09-16 03:36:05.696 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=5916
24642006-09-16 03:36:05.702 NVP: A/V Divergence: -0.958325, Rate: -0.022225, Warpfactor: 0.992085, warpfactor_avg: 0.999928
24652006-09-16 03:36:05.703 AO: Broadcasting free space avail
24662006-09-16 03:36:05.703 AO: audio waiting for buffer to fill: have 1024 want 4096
24672006-09-16 03:36:05.703 AO: Broadcasting free space avail
24682006-09-16 03:36:05.721 AO: audio waiting for buffer to fill: have 1024 want 4096
24692006-09-16 03:36:05.721 AO: Broadcasting free space avail
24702006-09-16 03:36:05.729 AO: audio waiting for buffer to fill: have 1024 want 4096
24712006-09-16 03:36:05.729 AO: Broadcasting free space avail
24722006-09-16 03:36:05.730 AFD: video timecode 529234 529234 5880 5940
24732006-09-16 03:36:05.732 AFD: audio timecode 534603 534603 5940 5964
24742006-09-16 03:36:05.733 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=5940
24752006-09-16 03:36:05.736 AFD: audio timecode 536763 536763 5964 5988
24762006-09-16 03:36:05.736 AO: _AddSamples bytes=4608, used=5633, free=762367, timecode=5964
24772006-09-16 03:36:05.739 AO: Broadcasting free space avail
24782006-09-16 03:36:05.744 AO: Broadcasting free space avail
24792006-09-16 03:36:05.744 AO: audio waiting for buffer to fill: have 2048 want 4096
24802006-09-16 03:36:05.744 AO: Broadcasting free space avail
24812006-09-16 03:36:05.749 NVP: A/V Divergence: -1.01873, Rate: -0.0604, Warpfactor: 0.991589, warpfactor_avg: 0.999928
24822006-09-16 03:36:05.757 AO: audio waiting for buffer to fill: have 2048 want 4096
24832006-09-16 03:36:05.757 AO: Broadcasting free space avail
24842006-09-16 03:36:05.769 AO: audio waiting for buffer to fill: have 2048 want 4096
24852006-09-16 03:36:05.769 AO: Broadcasting free space avail
24862006-09-16 03:36:05.773 AFD: video timecode 543634 532834 5920 5988
24872006-09-16 03:36:05.775 AFD: audio timecode 538923 538923 5988 6012
24882006-09-16 03:36:05.775 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=5988
24892006-09-16 03:36:05.779 AFD: audio timecode 541083 541083 6012 6036
24902006-09-16 03:36:05.779 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=6012
24912006-09-16 03:36:05.784 AO: Broadcasting free space avail
24922006-09-16 03:36:05.784 AO: Broadcasting free space avail
24932006-09-16 03:36:05.784 AO: audio waiting for buffer to fill: have 3072 want 4096
24942006-09-16 03:36:05.785 AO: Broadcasting free space avail
24952006-09-16 03:36:05.790 NVP: A/V Divergence: -1.06403, Rate: -0.0453, Warpfactor: 0.991093, warpfactor_avg: 0.999927
24962006-09-16 03:36:05.799 AO: audio waiting for buffer to fill: have 3072 want 4096
24972006-09-16 03:36:05.799 AO: Broadcasting free space avail
24982006-09-16 03:36:05.810 AO: audio waiting for buffer to fill: have 3072 want 4096
24992006-09-16 03:36:05.810 AO: Broadcasting free space avail
25002006-09-16 03:36:05.817 AFD: video timecode 536434 536434 5960 6036
25012006-09-16 03:36:05.821 AO: audio waiting for buffer to fill: have 3072 want 4096
25022006-09-16 03:36:05.821 AO: Broadcasting free space avail
25032006-09-16 03:36:05.821 AFD: audio timecode 543243 543243 6036 6060
25042006-09-16 03:36:05.822 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=6036
25052006-09-16 03:36:05.832 NVP: A/V Divergence: -1.0855, Rate: -0.021475, Warpfactor: 0.991072, warpfactor_avg: 0.999927
25062006-09-16 03:36:05.833 AO: Broadcasting free space avail
25072006-09-16 03:36:05.834 AO: audio waiting for buffer to fill: have 3584 want 4096
25082006-09-16 03:36:05.834 AO: Broadcasting free space avail
25092006-09-16 03:36:05.849 AO: audio waiting for buffer to fill: have 3584 want 4096
25102006-09-16 03:36:05.849 AO: Broadcasting free space avail
25112006-09-16 03:36:05.857 AO: audio waiting for buffer to fill: have 3584 want 4096
25122006-09-16 03:36:05.858 AO: Broadcasting free space avail
25132006-09-16 03:36:05.861 AFD: video timecode 540034 540034 6000 6060
25142006-09-16 03:36:05.869 AFD: audio timecode 545403 545403 6060 6084
25152006-09-16 03:36:05.870 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=6060
25162006-09-16 03:36:05.871 AFD: audio timecode 547563 547563 6084 6108
25172006-09-16 03:36:05.872 AO: _AddSamples bytes=4608, used=8193, free=759807, timecode=6084
25182006-09-16 03:36:05.872 AFD: positionMap[ 10 ] == 4644902.
25192006-09-16 03:36:05.876 AO: Broadcasting free space avail
25202006-09-16 03:36:05.877 AO: Broadcasting free space avail
25212006-09-16 03:36:05.877 AO: Broadcasting free space avail
25222006-09-16 03:36:05.877 AO: audio waiting for buffer to fill: have 512 want 4096
25232006-09-16 03:36:05.877 AO: Broadcasting free space avail
25242006-09-16 03:36:05.887 AO: audio waiting for buffer to fill: have 512 want 4096
25252006-09-16 03:36:05.887 AO: Broadcasting free space avail
25262006-09-16 03:36:05.891 NVP: A/V Divergence: -1.24538, Rate: -0.159875, Warpfactor: 0.990576, warpfactor_avg: 0.999926
25272006-09-16 03:36:05.905 AO: audio waiting for buffer to fill: have 512 want 4096
25282006-09-16 03:36:05.905 AO: Broadcasting free space avail
25292006-09-16 03:36:05.910 NVP: prebuffering pause
25302006-09-16 03:36:05.911 NVP: Waiting for prebuffer.. 0 AAALAALAAAAAADDA
25312006-09-16 03:36:05.917 AO: OutputAudioLoop: audio paused
25322006-09-16 03:36:05.918 AO: 56800 bytes free on soundcard
25332006-09-16 03:36:05.926 AO: 58496 bytes free on soundcard
25342006-09-16 03:36:05.929 AFD: video timecode 554434 543634 6040 6108
25352006-09-16 03:36:05.934 AFD: audio timecode 549723 549723 6108 6132
25362006-09-16 03:36:05.934 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=6108
25372006-09-16 03:36:05.936 AFD: audio timecode 551883 551883 6132 6156
25382006-09-16 03:36:05.936 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=6132
25392006-09-16 03:36:05.938 AO: 60640 bytes free on soundcard
25402006-09-16 03:36:05.953 AO: 63552 bytes free on soundcard
25412006-09-16 03:36:05.961 AO: 61024 bytes free on soundcard
25422006-09-16 03:36:05.962 AFD: video timecode 547234 547234 6080 6156
25432006-09-16 03:36:05.963 AFD: audio timecode 554043 554043 6156 6180
25442006-09-16 03:36:05.964 AO: _AddSamples bytes=4608, used=9729, free=758271, timecode=6156
25452006-09-16 03:36:05.972 NVP: A/V Divergence: -1.25902, Rate: -0.01365, Warpfactor: 0.990081, warpfactor_avg: 0.999925
25462006-09-16 03:36:05.973 AO: OutputAudioLoop: Play Event
25472006-09-16 03:36:05.974 AO: Broadcasting free space avail
25482006-09-16 03:36:05.974 AO: Broadcasting free space avail
25492006-09-16 03:36:05.974 AO: Broadcasting free space avail
25502006-09-16 03:36:05.974 AO: audio waiting for buffer to fill: have 2048 want 4096
25512006-09-16 03:36:05.975 AO: Broadcasting free space avail
25522006-09-16 03:36:05.979 NVP: A/V Divergence: -1.3005, Rate: -0.041475, Warpfactor: 0.989586, warpfactor_avg: 0.999925
25532006-09-16 03:36:05.989 AO: audio waiting for buffer to fill: have 2048 want 4096
25542006-09-16 03:36:05.990 AO: Broadcasting free space avail
25552006-09-16 03:36:05.999 AO: audio waiting for buffer to fill: have 2048 want 4096
25562006-09-16 03:36:06.000 AO: Broadcasting free space avail
25572006-09-16 03:36:06.005 AFD: video timecode 550834 550834 6120 6180
25582006-09-16 03:36:06.009 AO: audio waiting for buffer to fill: have 2048 want 4096
25592006-09-16 03:36:06.009 AO: Broadcasting free space avail
25602006-09-16 03:36:06.008 AFD: audio timecode 556203 556203 6180 6204
25612006-09-16 03:36:06.011 AO: _AddSamples bytes=4608, used=2049, free=765951, timecode=6180
25622006-09-16 03:36:06.013 AFD: audio timecode 558363 558363 6204 6228
25632006-09-16 03:36:06.013 AO: _AddSamples bytes=4608, used=6657, free=761343, timecode=6204
25642006-09-16 03:36:06.020 NVP: A/V Divergence: -1.33162, Rate: -0.031125, Warpfactor: 0.989091, warpfactor_avg: 0.999924
25652006-09-16 03:36:06.021 AO: Broadcasting free space avail
25662006-09-16 03:36:06.021 AO: Broadcasting free space avail
25672006-09-16 03:36:06.021 AO: audio waiting for buffer to fill: have 3072 want 4096
25682006-09-16 03:36:06.022 AO: Broadcasting free space avail
25692006-09-16 03:36:06.033 AO: audio waiting for buffer to fill: have 3072 want 4096
25702006-09-16 03:36:06.034 AO: Broadcasting free space avail
25712006-09-16 03:36:06.041 AO: audio waiting for buffer to fill: have 3072 want 4096
25722006-09-16 03:36:06.042 AO: Broadcasting free space avail
25732006-09-16 03:36:06.049 AFD: video timecode 565233 554434 6160 6228
25742006-09-16 03:36:06.051 AFD: audio timecode 560523 560523 6228 6252
25752006-09-16 03:36:06.051 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=6228
25762006-09-16 03:36:06.055 AO: Broadcasting free space avail
25772006-09-16 03:36:06.055 AO: audio waiting for buffer to fill: have 3584 want 4096
25782006-09-16 03:36:06.055 AO: Broadcasting free space avail
25792006-09-16 03:36:06.058 AFD: audio timecode 562683 562683 6252 6276
25802006-09-16 03:36:06.059 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=6252
25812006-09-16 03:36:06.061 AO: Broadcasting free space avail
25822006-09-16 03:36:06.062 AO: Broadcasting free space avail
25832006-09-16 03:36:06.062 AO: Broadcasting free space avail
25842006-09-16 03:36:06.071 NVP: A/V Divergence: -1.39245, Rate: -0.060825, Warpfactor: 0.988597, warpfactor_avg: 0.999923
25852006-09-16 03:36:06.074 AO: Broadcasting free space avail
25862006-09-16 03:36:06.090 AO: Broadcasting free space avail
25872006-09-16 03:36:06.093 AFD: video timecode 558033 558033 6200 6276
25882006-09-16 03:36:06.095 AFD: audio timecode 564843 564843 6276 6300
25892006-09-16 03:36:06.095 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=6276
25902006-09-16 03:36:06.103 AO: Broadcasting free space avail
25912006-09-16 03:36:06.103 AO: audio waiting for buffer to fill: have 512 want 4096
25922006-09-16 03:36:06.104 AO: Broadcasting free space avail
25932006-09-16 03:36:06.108 NVP: A/V Divergence: -1.45057, Rate: -0.058125, Warpfactor: 0.988102, warpfactor_avg: 0.999922
25942006-09-16 03:36:06.109 AO: audio waiting for buffer to fill: have 512 want 4096
25952006-09-16 03:36:06.110 AO: Broadcasting free space avail
25962006-09-16 03:36:06.125 AO: audio waiting for buffer to fill: have 512 want 4096
25972006-09-16 03:36:06.125 AO: Broadcasting free space avail
25982006-09-16 03:36:06.137 AO: audio waiting for buffer to fill: have 512 want 4096
25992006-09-16 03:36:06.138 AO: Broadcasting free space avail
26002006-09-16 03:36:06.138 AFD: video timecode 561633 561633 6240 6300
26012006-09-16 03:36:06.139 AFD: audio timecode 567003 567003 6300 6324
26022006-09-16 03:36:06.140 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=6300
26032006-09-16 03:36:06.143 AFD: audio timecode 569163 569163 6324 6348
26042006-09-16 03:36:06.143 AO: _AddSamples bytes=4608, used=5121, free=762879, timecode=6324
26052006-09-16 03:36:06.150 AO: Broadcasting free space avail
26062006-09-16 03:36:06.150 AO: Broadcasting free space avail
26072006-09-16 03:36:06.150 AO: audio waiting for buffer to fill: have 1536 want 4096
26082006-09-16 03:36:06.151 AO: Broadcasting free space avail
26092006-09-16 03:36:06.157 NVP: A/V Divergence: -1.55667, Rate: -0.1061, Warpfactor: 0.987608, warpfactor_avg: 0.999922
26102006-09-16 03:36:06.159 AO: audio waiting for buffer to fill: have 1536 want 4096
26112006-09-16 03:36:06.159 AO: Broadcasting free space avail
26122006-09-16 03:36:06.173 AO: audio waiting for buffer to fill: have 1536 want 4096
26132006-09-16 03:36:06.173 AO: Broadcasting free space avail
26142006-09-16 03:36:06.181 AO: audio waiting for buffer to fill: have 1536 want 4096
26152006-09-16 03:36:06.181 AO: Broadcasting free space avail
26162006-09-16 03:36:06.186 AFD: video timecode 576033 565233 6280 6348
26172006-09-16 03:36:06.188 AFD: audio timecode 571323 571323 6348 6372
26182006-09-16 03:36:06.188 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=6348
26192006-09-16 03:36:06.193 AFD: audio timecode 573483 573483 6372 6396
26202006-09-16 03:36:06.194 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=6372
26212006-09-16 03:36:06.196 AO: Broadcasting free space avail
26222006-09-16 03:36:06.196 AO: Broadcasting free space avail
26232006-09-16 03:36:06.197 AO: audio waiting for buffer to fill: have 2560 want 4096
26242006-09-16 03:36:06.197 AO: Broadcasting free space avail
26252006-09-16 03:36:06.198 NVP: A/V Divergence: -1.53, Rate: 0.026675, Warpfactor: 0.987896, warpfactor_avg: 0.999921
26262006-09-16 03:36:06.213 AO: audio waiting for buffer to fill: have 2560 want 4096
26272006-09-16 03:36:06.213 AO: Broadcasting free space avail
26282006-09-16 03:36:06.224 AO: audio waiting for buffer to fill: have 2560 want 4096
26292006-09-16 03:36:06.225 AO: Broadcasting free space avail
26302006-09-16 03:36:06.225 AFD: video timecode 568833 568833 6320 6396
26312006-09-16 03:36:06.229 AFD: audio timecode 575643 575643 6396 6420
26322006-09-16 03:36:06.230 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=6396
26332006-09-16 03:36:06.238 AO: Broadcasting free space avail
26342006-09-16 03:36:06.238 AO: audio waiting for buffer to fill: have 3072 want 4096
26352006-09-16 03:36:06.239 AO: Broadcasting free space avail
26362006-09-16 03:36:06.246 AO: audio waiting for buffer to fill: have 3072 want 4096
26372006-09-16 03:36:06.247 AO: Broadcasting free space avail
26382006-09-16 03:36:06.254 AFD: video timecode 572433 572433 6360 6420
26392006-09-16 03:36:06.256 AFD: audio timecode 577803 577803 6420 6444
26402006-09-16 03:36:06.256 AO: _AddSamples bytes=4608, used=3073, free=764927, timecode=6420
26412006-09-16 03:36:06.258 AFD: audio timecode 579963 579963 6444 6468
26422006-09-16 03:36:06.258 AO: _AddSamples bytes=4608, used=7681, free=760319, timecode=6444
26432006-09-16 03:36:06.260 AO: Broadcasting free space avail
26442006-09-16 03:36:06.261 AO: Broadcasting free space avail
26452006-09-16 03:36:06.261 AO: Broadcasting free space avail
26462006-09-16 03:36:06.261 AO: Broadcasting free space avail
26472006-09-16 03:36:06.265 NVP: A/V Divergence: -1.69125, Rate: -0.16125, Warpfactor: 0.987402, warpfactor_avg: 0.99992
26482006-09-16 03:36:06.275 AO: Broadcasting free space avail
26492006-09-16 03:36:06.286 AO: Broadcasting free space avail
26502006-09-16 03:36:06.293 AFD: video timecode 586833 576033 6400 6468
26512006-09-16 03:36:06.296 AFD: audio timecode 582123 582123 6468 6492
26522006-09-16 03:36:06.296 AO: _AddSamples bytes=4608, used=1, free=767999, timecode=6468
26532006-09-16 03:36:06.297 AO: Broadcasting free space avail
26542006-09-16 03:36:06.297 AO: audio waiting for buffer to fill: have 512 want 4096
26552006-09-16 03:36:06.297 AO: Broadcasting free space avail
26562006-09-16 03:36:06.302 AFD: audio timecode 584283 584283 6492 6516
26572006-09-16 03:36:06.302 AO: _AddSamples bytes=4608, used=513, free=767487, timecode=6492
26582006-09-16 03:36:06.308 NVP: A/V Divergence: -1.82467, Rate: -0.133425, Warpfactor: 0.986908, warpfactor_avg: 0.999919
26592006-09-16 03:36:06.311 AO: Broadcasting free space avail
26602006-09-16 03:36:06.312 AO: audio waiting for buffer to fill: have 1024 want 4096
26612006-09-16 03:36:06.312 AO: Broadcasting free space avail
26622006-09-16 03:36:06.317 NVP: A/V Divergence: -1.831, Rate: -0.006325, Warpfactor: 0.986415, warpfactor_avg: 0.999918
26632006-09-16 03:36:06.323 AO: audio waiting for buffer to fill: have 1024 want 4096
26642006-09-16 03:36:06.324 AO: Broadcasting free space avail
26652006-09-16 03:36:06.334 AO: audio waiting for buffer to fill: have 1024 want 4096
26662006-09-16 03:36:06.334 AO: Broadcasting free space avail
26672006-09-16 03:36:06.343 AO: audio waiting for buffer to fill: have 1024 want 4096
26682006-09-16 03:36:06.343 AO: Broadcasting free space avail
26692006-09-16 03:36:06.345 AFD: video timecode 579633 579633 6440 6516
26702006-09-16 03:36:06.347 AFD: audio timecode 586443 586443 6516 6540
26712006-09-16 03:36:06.347 AO: _AddSamples bytes=4608, used=1025, free=766975, timecode=6516
26722006-09-16 03:36:06.349 AO: Broadcasting free space avail
26732006-09-16 03:36:06.350 AO: audio waiting for buffer to fill: have 1536 want 4096
26742006-09-16 03:36:06.350 AO: Broadcasting free space avail
26752006-09-16 03:36:06.365 AO: audio waiting for buffer to fill: have 1536 want 4096
26762006-09-16 03:36:06.366 AO: Broadcasting free space avail
26772006-09-16 03:36:06.374 AO: audio waiting for buffer to fill: have 1536 want 4096
26782006-09-16 03:36:06.375 AO: Broadcasting free space avail
26792006-09-16 03:36:06.377 AFD: video timecode 583233 583233 6480 6540
26802006-09-16 03:36:06.380 AFD: audio timecode 588603 588603 6540 6564
26812006-09-16 03:36:06.381 AO: _AddSamples bytes=4608, used=1537, free=766463, timecode=6540
26822006-09-16 03:36:06.386 AFD: audio timecode 590763 590763 6564 6588
26832006-09-16 03:36:06.387 AO: _AddSamples bytes=4608, used=6145, free=761855, timecode=6564
26842006-09-16 03:36:06.387 AFD: positionMap[ 11 ] == 5056550.
26852006-09-16 03:36:06.393 AO: Broadcasting free space avail
26862006-09-16 03:36:06.393 AO: Broadcasting free space avail
26872006-09-16 03:36:06.394 AO: audio waiting for buffer to fill: have 2560 want 4096
26882006-09-16 03:36:06.394 AO: Broadcasting free space avail
26892006-09-16 03:36:06.395 NVP: A/V Divergence: -1.97325, Rate: -0.14225, Warpfactor: 0.985922, warpfactor_avg: 0.999917
26902006-09-16 03:36:06.407 NVP: A/V Divergence: -1.99242, Rate: -0.019175, Warpfactor: 0.985429, warpfactor_avg: 0.999916
26912006-09-16 03:36:06.410 AO: audio waiting for buffer to fill: have 2560 want 4096
26922006-09-16 03:36:06.411 AO: Broadcasting free space avail
26932006-09-16 03:36:06.414 NVP: prebuffering pause
26942006-09-16 03:36:06.415 NVP: Waiting for prebuffer.. 0 LAAAAAADAAADAAAL
26952006-09-16 03:36:06.421 AO: OutputAudioLoop: audio paused
26962006-09-16 03:36:06.421 AO: 59328 bytes free on soundcard
26972006-09-16 03:36:06.436 AO: 62048 bytes free on soundcard
26982006-09-16 03:36:06.439 TV: Attempting to change from WatchingLiveTV to None
26992006-09-16 03:36:06.444 TV: StopStuff() -- begin
27002006-09-16 03:36:06.444 TV: StopStuff(): stopping ring buffer[s]
27012006-09-16 03:36:06.445 AFD: video timecode 597633 586833 6520 6588
27022006-09-16 03:36:06.447 AFD: audio timecode 592923 592923 6588 6612
27032006-09-16 03:36:06.447 AO: _AddSamples bytes=4608, used=2561, free=765439, timecode=6588
27042006-09-16 03:36:06.449 AO: 60512 bytes free on soundcard
27052006-09-16 03:36:06.451 AFD: audio timecode 595082 595082 6612 6636
27062006-09-16 03:36:06.452 AO: _AddSamples bytes=4608, used=7169, free=760831, timecode=6612
27072006-09-16 03:36:06.461 AO: 62912 bytes free on soundcard
27082006-09-16 03:36:06.470 NVP: A/V Divergence: -1.83805, Rate: 0.154375, Warpfactor: 0.985921, warpfactor_avg: 0.999915
27092006-09-16 03:36:06.472 AO: OutputAudioLoop: Play Event
27102006-09-16 03:36:06.473 TV: StopStuff(): stopping player[s] (1/2)
27112006-09-16 03:36:06.473 TV: StopStuff(): stopping recorder[s]
27122006-09-16 03:36:06.474 write -> 15 32      QUERY_RECORDER 1[]:[]STOP_LIVETV
27132006-09-16 03:36:06.472 AO: Broadcasting free space avail
27142006-09-16 03:36:06.474 AO: Broadcasting free space avail
27152006-09-16 03:36:06.480 AO: audio waiting for buffer to fill: have 3584 want 4096
27162006-09-16 03:36:06.481 AO: Broadcasting free space avail
27172006-09-16 03:36:06.485 AO: audio waiting for buffer to fill: have 3584 want 4096
27182006-09-16 03:36:06.486 AO: Broadcasting free space avail
27192006-09-16 03:36:06.489 NVP: prebuffering pause
27202006-09-16 03:36:06.489 NVP: Waiting for prebuffer.. 0 LAAAAAAAAAAAALAd
27212006-09-16 03:36:06.493 AO: OutputAudioLoop: audio paused
27222006-09-16 03:36:06.493 AO: 56736 bytes free on soundcard
27232006-09-16 03:36:06.501 AO: 58240 bytes free on soundcard
27242006-09-16 03:36:06.509 MythSocket(852b5e8:11): socket is readable
27252006-09-16 03:36:06.509 MythSocket(852b5e8:11): cb->readyRead()
27262006-09-16 03:36:06.510 read  <- 11 49      BACKEND_MESSAGE[]:[]DONE_RECORDING 1 10[]:[]empty
27272006-09-16 03:36:06.510 AO: 59936 bytes free on soundcard
27282006-09-16 03:36:06.511 AFD: video timecode 590433 590433 6560 6636
27292006-09-16 03:36:06.513 AFD: audio timecode 597242 597242 6636 6660
27302006-09-16 03:36:06.513 AO: _AddSamples bytes=4608, used=3585, free=764415, timecode=6636
27312006-09-16 03:36:06.514 NVP: Exited decoder loop.
27322006-09-16 03:36:06.517 AO: 61376 bytes free on soundcard
27332006-09-16 03:36:06.525 VideoOutputXv: dtor
27342006-09-16 03:36:06.525 VideoOutputXv: DiscardFrames(1)
27352006-09-16 03:36:06.525 VideoOutputXv: DiscardFrames() 1: LAAAAAAAAAAAAuAd
27362006-09-16 03:36:06.526 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding.
27372006-09-16 03:36:06.526 VideoOutputXv: DiscardFrames() 2: DAAAAAAAAAAAAdAd
27382006-09-16 03:36:06.526 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding.
27392006-09-16 03:36:06.526 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding.
27402006-09-16 03:36:06.527 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAdAd -- done()
27412006-09-16 03:36:06.527 VideoOutputXv: DiscardFrames(1)
27422006-09-16 03:36:06.527 VideoOutputXv: DiscardFrames() 1: AAAAAAAAAAAAAdAd
27432006-09-16 03:36:06.528 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding.
27442006-09-16 03:36:06.528 VideoOutputXv: DiscardFrames() 2: AAAAAAAAAAAAAdAd
27452006-09-16 03:36:06.528 VideoOutputXv: Frame f is in use by avlib and so is being held for later discarding.
27462006-09-16 03:36:06.528 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAdAd -- done()
27472006-09-16 03:36:06.529 AO: 63584 bytes free on soundcard
27482006-09-16 03:36:06.537 AO: 61024 bytes free on soundcard
27492006-09-16 03:36:06.540 VideoOutputXv: Closing XVideo port 68
27502006-09-16 03:36:06.542 AO: Killing AudioOutputDSP
27512006-09-16 03:36:06.545 AO: OutputAudioLoop: Stop Event
27522006-09-16 03:36:06.545 AO: kickoffOutputAudioLoop exiting
27532006-09-16 03:36:06.679 MythSocket(852b5e8:11): socket is readable
27542006-09-16 03:36:06.680 MythSocket(852b5e8:11): cb->readyRead()
27552006-09-16 03:36:06.680 read  <- 11 78      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr-2006-09-16T03:35...
27562006-09-16 03:36:06.695 MythSocket(852b5e8:11): socket is readable
27572006-09-16 03:36:06.695 MythSocket(852b5e8:11): cb->readyRead()
27582006-09-16 03:36:06.696 read  <- 11 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
27592006-09-16 03:36:06.698 read  <- 15 2       ok
27602006-09-16 03:36:06.698 TV: StopStuff(): stopping player[s] (2/2)
27612006-09-16 03:36:06.702 write -> 12 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
27622006-09-16 03:36:06.704 read  <- 12 2       OK
27632006-09-16 03:36:06.705 MythSocket(852b5e8:11): socket is readable
27642006-09-16 03:36:06.705 MythSocket(852b5e8:11): cb->readyRead()
27652006-09-16 03:36:06.706 read  <- 11 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
27662006-09-16 03:36:06.810 MythSocket(85392f8:15): DownRef: -1
27672006-09-16 03:36:06.811 MythSocket(85392f8:15): state change Connected -> Idle
27682006-09-16 03:36:06.812 MythSocket(85392f8:-1): delete socket
27692006-09-16 03:36:06.825 TV: StopStuff() -- end
27702006-09-16 03:36:06.825 TV: Changing from WatchingLiveTV to None
27712006-09-16 03:36:06.842 DPMS Reactivated.
27722006-09-16 03:36:09.789 write -> 12 31      QUERY_IS_ACTIVE_BACKEND[]:[]pvr
27732006-09-16 03:36:09.791 read  <- 12 4       TRUE
27742006-09-16 03:36:11.698 MythSocket(84feee8:12): DownRef: -1
27752006-09-16 03:36:11.699 MythSocket(84feee8:12): state change Connected -> Idle
27762006-09-16 03:36:11.699 MythSocket(84feee8:-1): delete socket
27772006-09-16 03:36:11.699 MythSocket(852b5e8:11): DownRef: 0
27782006-09-16 03:36:11.706 MythSocket(852b5e8:11): DownRef: -1
27792006-09-16 03:36:11.706 MythSocket(852b5e8:11): state change Connected -> Idle
27802006-09-16 03:36:11.706 MythSocket(852b5e8:-1): delete socket
27812006-09-16 03:36:11.866 MythSocket: readyread thread exit