Unable to ignore SIGPIPE 2009-04-21 14:51:43.723 user: 0 effective user: 0 before privileged thread 2009-04-21 14:51:43.723 user: 0 effective user: 0 after privileged thread 2009-04-21 14:51:43.723 user: 0 effective user: 0 run_priv_thread 2009-04-21 14:51:43.723 New DB connection, total: 2 2009-04-21 14:51:43.723 Connected to database 'mythconverg' at host: 192.168.1.1 2009-04-21 14:51:43.723 mythfrontend version: 0.21.20080304-1 www.mythtv.org 2009-04-21 14:51:43.723 Enabled verbose msgs: important general playback audio timestamp 2009-04-21 14:51:44.083 No theme dir: C:/Documents and Settings/jllu1/Application Data/.mythtv/themes/MythCenter-wide 2009-04-21 14:51:44.083 Primary screen 0. 2009-04-21 14:51:44.083 Using screen 0, 1920x1200 at 0,0 2009-04-21 14:51:44.083 No theme dir: C:/Documents and Settings/jllu1/Application Data/.mythtv/themes/MythCenter-wide 2009-04-21 14:51:44.083 Switching to wide mode (MythCenter-wide) 2009-04-21 14:51:44.223 Using the OpenGL painter 2009-04-21 14:51:44.395 MythMainWindow::attach old: none, new: progress, thread: 536 2009-04-21 14:51:44.614 MythMainWindow::attach old: none, new: progress, thread: 536 2009-04-21 14:51:45.270 MythMainWindow::attach old: none, new: progress, thread: 536 2009-04-21 14:51:46.520 Loading from: c:/mythtv/build//share/mythtv/themes/MythCenter-wide/base.xml 2009-04-21 14:51:46.536 Loading from: c:/mythtv/build//share/mythtv/themes/default/base.xml 2009-04-21 14:51:46.708 Registering Internal as a media playback plugin. 2009-04-21 14:51:46.708 Error. Couldn't create MythMediaDevice. 2009-04-21 14:51:46.708 No theme dir: C:/Documents and Settings/jllu1/Application Data/.mythtv/themes/MythCenter-wide 2009-04-21 14:51:46.848 Using NV NPOT texture extension 2009-04-21 14:51:47.879 MythMainWindow::attach old: none, new: video playback window, thread: 536 2009-04-21 14:51:47.879 Connecting to backend server: 192.168.1.1:6543 (try 1 of 5) 2009-04-21 14:51:47.895 Using protocol version 40 2009-04-21 14:51:47.895 Event socket closed. No connection to the backend. 2009-04-21 14:51:47.911 Connecting to backend server: 192.168.1.1:6543 (try 1 of 5) 2009-04-21 14:51:47.911 Using protocol version 40 2009-04-21 14:51:47.911 Event socket closed. No connection to the backend. 2009-04-21 14:51:47.926 TV: Attempting to change from None to WatchingLiveTV 2009-04-21 14:51:47.926 Using protocol version 40 2009-04-21 14:51:49.176 LiveTVChain(live-cheetah-2009-04-21T14:51:47): ReloadAll(): Added new recording 2009-04-21 14:51:49.208 RingBuf(myth://192.168.1.1:6543/1061_20090421145148.nuv): OpenFile(myth://192.168.1.1:6543/1061_20090421145148.nuv, 12) 2009-04-21 14:51:49.223 RingBuf(myth://192.168.1.1:6543/1061_20090421145148.nuv): CalcReadAheadThresh(109048128 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2009-04-21 14:51:49.239 TV: StartRecorder(): took 16 ms to start recorder. 2009-04-21 14:51:49.254 New DB connection, total: 3 2009-04-21 14:51:49.270 Connected to database 'mythconverg' at host: 192.168.1.1 2009-04-21 14:51:49.270 Connecting to backend server: 192.168.1.1:6543 (try 1 of 5) 2009-04-21 14:51:49.270 Using protocol version 40 2009-04-21 14:51:49.286 Event socket closed. No connection to the backend. 2009-04-21 14:51:49.286 Connecting to backend server: 192.168.1.1:6543 (try 1 of 5) 2009-04-21 14:51:49.301 Using protocol version 40 2009-04-21 14:51:49.301 Event socket closed. No connection to the backend. 2009-04-21 14:51:49.348 detectInterlace(Ignore Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan 2009-04-21 14:51:49.364 AO: Killing AudioOutputDSP 2009-04-21 14:51:49.458 Opening audio device 'Windows:'. ch 2(2) sr 44100 2009-04-21 14:51:49.458 GetVolume(0) 100(4292673500) 2009-04-21 14:51:49.458 AO: Audio fragment size: 6144 2009-04-21 14:51:49.458 AO: Audio Stretch Factor: 1 2009-04-21 14:51:49.458 Audio Codec Used: not set 2009-04-21 14:51:49.458 AO: Ending reconfigure 2009-04-21 14:51:49.458 AO: no change exiting 2009-04-21 14:51:49.458 AO: kickoffOutputAudioLoop: pid = 5008 2009-04-21 14:51:49.458 AO: OutputAudioLoop: Play Event 2009-04-21 14:51:49.536 RingBuf(myth://192.168.1.1:6543/1061_20090421145148.nuv): CalcReadAheadThresh(2089878056 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2009-04-21 14:51:49.536 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0) 2009-04-21 14:51:49.536 SyncPositionMap watchingrecording, from DB: 0 entries 2009-04-21 14:51:49.536 Filling position map from 0 to 6 2009-04-21 14:51:49.536 Position map filled from Encoder to: 0 2009-04-21 14:51:49.536 SyncPositionMap watchingrecording total: 1 entries 2009-04-21 14:51:49.536 SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1 2009-04-21 14:51:49.536 AO: no change exiting 2009-04-21 14:51:49.536 AO: Pause 1 2009-04-21 14:51:49.536 VideoOutput: Allowed renderers: direct3d,directx 2009-04-21 14:51:49.536 VideoOutput: Allowed renderers (filt: nuppel): directx,direct3d 2009-04-21 14:51:49.551 AO: OutputAudioLoop: audio paused 2009-04-21 14:51:49.551 AO: 98304 bytes free on soundcard 2009-04-21 14:51:49.551 AO: 92160 bytes free on soundcard 2009-04-21 14:51:49.551 AO: 86016 bytes free on soundcard 2009-04-21 14:51:49.551 AO: 79872 bytes free on soundcard 2009-04-21 14:51:49.551 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt() 2009-04-21 14:51:49.551 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(4) rend(direct3d) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt() 2009-04-21 14:51:49.551 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt() 2009-04-21 14:51:49.551 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt() 2009-04-21 14:51:49.551 VDP: LoadBestPreferences(2048x2048, 0) 2009-04-21 14:51:49.551 VDP: LoadBestPreferences(2048x2048, 60) 2009-04-21 14:51:49.551 VDP: LoadBestPreferences(640x480, 60) 2009-04-21 14:51:49.551 VideoOutput: Preferred renderer: direct3d 2009-04-21 14:51:49.551 VideoOutput: Trying video renderer: direct3d 2009-04-21 14:51:49.551 AO: 73728 bytes free on soundcard 2009-04-21 14:51:49.551 AO: 67584 bytes free on soundcard 2009-04-21 14:51:49.567 AO: 61440 bytes free on soundcard 2009-04-21 14:51:49.567 AO: 55296 bytes free on soundcard 2009-04-21 14:51:49.567 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt() 2009-04-21 14:51:49.567 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(4) rend(direct3d) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt() 2009-04-21 14:51:49.567 VDP: Accepting: cmp(>= 1920 1080) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt() 2009-04-21 14:51:49.567 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(yadifdoubleprocessdeint,yadifdeint) filt() 2009-04-21 14:51:49.567 VDP: LoadBestPreferences(2048x2048, 0) 2009-04-21 14:51:49.567 VDP: LoadBestPreferences(2048x2048, 60) 2009-04-21 14:51:49.567 AO: 49152 bytes free on soundcard 2009-04-21 14:51:49.567 AO: 43008 bytes free on soundcard 2009-04-21 14:51:49.567 AO: 36864 bytes free on soundcard 2009-04-21 14:51:49.583 AO: 30720 bytes free on soundcard 2009-04-21 14:51:49.583 AO: 24576 bytes free on soundcard 2009-04-21 14:51:49.583 AO: 18432 bytes free on soundcard 2009-04-21 14:51:49.583 AO: 12288 bytes free on soundcard 2009-04-21 14:51:49.598 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.598 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.598 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.598 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.614 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.614 VideoOutputD3D: ctor 2009-04-21 14:51:49.614 VideoOutputD3D: Init w=640 h=480 2009-04-21 14:51:49.614 VDP: SetVideoRenderer(direct3d) 2009-04-21 14:51:49.614 VDP: Old preferences: rend(xv-blit) osd(softblend) deint(none,none) filt() 2009-04-21 14:51:49.614 VDP: New preferences: rend(direct3d) osd(softblend) deint(none,none) filt() 2009-04-21 14:51:49.614 XOff: 0, YOff: 0 2009-04-21 14:51:49.614 VDP: LoadBestPreferences(640x480, 60) 2009-04-21 14:51:49.614 Display Rect left: 124, top: 0, width: 744, height: 558, aspect: 1.77778 2009-04-21 14:51:49.614 Video Rect left: 0, top: 0, width: 640, height: 480, aspect: 1.33333 2009-04-21 14:51:49.614 Created data @0x5e78010->0x5ee8812 2009-04-21 14:51:49.614 Created data @0x5ee8870->0x5f59072 2009-04-21 14:51:49.614 Created data @0x5f590d0->0x5fc98d2 2009-04-21 14:51:49.614 Created data @0x5fc9930->0x603a132 2009-04-21 14:51:49.614 Created data @0x603a190->0x60aa992 2009-04-21 14:51:49.614 Created data @0x60aa9f0->0x611b1f2 2009-04-21 14:51:49.614 Created data @0x611b250->0x618ba52 2009-04-21 14:51:49.614 Created data @0x618bab0->0x61fc2b2 2009-04-21 14:51:49.614 Created data @0x61fc310->0x626cb12 2009-04-21 14:51:49.614 Created data @0x626cb70->0x62dd372 2009-04-21 14:51:49.614 Created data @0x62dd3d0->0x634dbd2 2009-04-21 14:51:49.614 Created data @0x634dc30->0x63be432 2009-04-21 14:51:49.614 Created data @0x7bc0050->0x7c30852 2009-04-21 14:51:49.614 Created data @0x7c308b0->0x7ca10b2 2009-04-21 14:51:49.614 Created data @0x7ca1110->0x7d11912 2009-04-21 14:51:49.614 Created data @0x7d11970->0x7d82172 2009-04-21 14:51:49.614 Created data @0x7d821d0->0x7df29d2 2009-04-21 14:51:49.614 Created data @0x7df2a30->0x7e63232 2009-04-21 14:51:49.614 Created data @0x7e63290->0x7ed3a92 2009-04-21 14:51:49.614 Created data @0x7ed3af0->0x7f442f2 2009-04-21 14:51:49.614 Created data @0x7f44350->0x7fb4b52 2009-04-21 14:51:49.614 Created data @0x7fb4bb0->0x80253b2 2009-04-21 14:51:49.614 Created data @0x8025410->0x8095c12 2009-04-21 14:51:49.614 Created data @0x8095c70->0x8106472 2009-04-21 14:51:49.614 Created data @0x81064d0->0x8176cd2 2009-04-21 14:51:49.614 Created data @0x8176d30->0x81e7532 2009-04-21 14:51:49.614 Created data @0x81e7590->0x8257d92 2009-04-21 14:51:49.614 Created data @0x8257df0->0x82c85f2 2009-04-21 14:51:49.614 Created data @0x82c8650->0x8338e52 2009-04-21 14:51:49.614 Created data @0x8338eb0->0x83a96b2 2009-04-21 14:51:49.614 Created data @0x83a9710->0x8419f12 2009-04-21 14:51:49.614 Created data @0x8419f70->0x848a772 2009-04-21 14:51:49.614 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.629 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.629 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.629 VideoOutputD3D: InitD3D start (x=640, y=480) 2009-04-21 14:51:49.629 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.645 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.645 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.661 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.661 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.661 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.676 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.676 VideoOutputD3D: Direct3D device adapter successfully initialized 2009-04-21 14:51:49.676 Display Rect left: 124, top: 0, width: 744, height: 558, aspect: 1.77778 2009-04-21 14:51:49.676 Video Rect left: 0, top: 0, width: 640, height: 480, aspect: 1.33333 2009-04-21 14:51:49.676 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.676 Over/underscan. V: 0, H: 0 2009-04-21 14:51:49.676 Display Rect left: 124, top: 0, width: 744, height: 558, aspect: 1.77778 2009-04-21 14:51:49.676 Video Rect left: 0, top: 0, width: 640, height: 480, aspect: 1.33333 2009-04-21 14:51:49.676 VDP: LoadBestPreferences(640x480, 29.97) 2009-04-21 14:51:49.676 NVP: LoadFilters(''..) -> 0 2009-04-21 14:51:49.692 OSD Theme Dimensions W: 640 H: 480 2009-04-21 14:51:49.692 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.692 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.692 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.692 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.708 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.723 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.723 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.723 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.723 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.739 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.739 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.754 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.754 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.754 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.770 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.770 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.786 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.786 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.801 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.801 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.801 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.817 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.817 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.833 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.833 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.833 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.848 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.848 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.864 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.864 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.879 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.879 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.879 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.895 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.895 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.895 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.895 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.911 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.926 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.926 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.942 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.942 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.942 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.942 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.958 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.973 AO: 6144 bytes free on soundcard 2009-04-21 14:51:49.973 AO: 0 bytes free on soundcard 2009-04-21 14:51:49.973 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.973 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.989 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:49.989 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.004 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.004 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.004 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.020 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.020 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.036 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.036 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.036 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.051 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.051 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.067 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.067 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.083 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.083 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.083 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.098 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.098 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.114 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.114 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.114 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.114 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.129 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.145 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.145 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.145 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.145 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.161 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.176 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.176 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.176 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.176 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.192 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.192 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.208 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.223 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.223 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.223 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.223 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.239 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.239 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.254 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.254 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.254 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.270 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.270 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.286 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.286 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.286 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.301 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.301 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.317 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.317 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.317 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.333 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.333 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.348 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.348 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.348 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.348 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.364 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.379 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.379 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.379 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.379 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.395 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.395 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.411 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.426 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.426 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.426 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.426 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.442 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.442 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.458 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.458 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.458 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.473 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.473 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.489 NVP: ClearAfterSeek(1) 2009-04-21 14:51:50.489 AO: Pause 1 2009-04-21 14:51:50.489 TV: StartPlayer(): took 1156 ms to start player. 2009-04-21 14:51:50.489 TV: Changing from None to WatchingLiveTV 2009-04-21 14:51:50.489 New DB connection, total: 4 2009-04-21 14:51:50.489 VDP: GetFilteredDeint() : direct3d -> 'yadifdoubleprocessdeint' 2009-04-21 14:51:50.489 Realtime priority would require SUID as root. 2009-04-21 14:51:50.489 Connected to database 'mythconverg' at host: 192.168.1.1 2009-04-21 14:51:50.489 AO: OutputAudioLoop: audio paused 2009-04-21 14:51:50.489 AO: waiting for space on soundcard to write zeros: have 0 need 6144 yadifdeint: size changed from 0 x 0 -> 640 x 480 2009-04-21 14:51:50.489 Using deinterlace method yadifdoubleprocessdeint 2009-04-21 14:51:50.489 Set video sync frame interval to 33366 2009-04-21 14:51:50.489 Using audio as timebase 2009-04-21 14:51:50.489 Video timing method: USleep with busy wait 2009-04-21 14:51:50.489 Refresh rate: 16666, frame interval: 33366 2009-04-21 14:51:50.489 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2009-04-21 14:51:50.489 AO: _AddSamples samples=1152 bytes=4608, used=1, free=1535999, timecode=19 needsupmix 0 2009-04-21 14:51:50.489 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.489 AO: _AddSamples samples=1152 bytes=4608, used=4609, free=1531391, timecode=42 needsupmix 0 2009-04-21 14:51:50.504 AO: _AddSamples samples=1152 bytes=4608, used=9217, free=1526783, timecode=66 needsupmix 0 2009-04-21 14:51:50.504 AO: _AddSamples samples=1152 bytes=4608, used=13825, free=1522175, timecode=89 needsupmix 0 2009-04-21 14:51:50.504 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.504 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.504 AO: _AddSamples samples=1152 bytes=4608, used=18433, free=1517567, timecode=113 needsupmix 0 2009-04-21 14:51:50.504 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.504 AO: _AddSamples samples=1152 bytes=4608, used=23041, free=1512959, timecode=135 needsupmix 0 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=27649, free=1508351, timecode=182 needsupmix 0 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=32257, free=1503743, timecode=205 needsupmix 0 2009-04-21 14:51:50.520 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=36865, free=1499135, timecode=228 needsupmix 0 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=41473, free=1494527, timecode=252 needsupmix 0 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=46081, free=1489919, timecode=275 needsupmix 0 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=50689, free=1485311, timecode=298 needsupmix 0 2009-04-21 14:51:50.520 AO: 6144 bytes free on soundcard 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=55297, free=1480703, timecode=321 needsupmix 0 2009-04-21 14:51:50.520 AO: _AddSamples samples=1152 bytes=4608, used=59905, free=1476095, timecode=345 needsupmix 0 2009-04-21 14:51:50.536 AO: 0 bytes free on soundcard 2009-04-21 14:51:50.536 AO: waiting for space on soundcard to write zeros: have 0 need 6144 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=64513, free=1471487, timecode=393 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=69121, free=1466879, timecode=414 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=73729, free=1462271, timecode=438 needsupmix 0 2009-04-21 14:51:50.536 AO: Pause 0 2009-04-21 14:51:50.536 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=461 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=484 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=87553, free=1448447, timecode=507 needsupmix 0 2009-04-21 14:51:50.536 AO: OutputAudioLoop: Play Event 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=92161, free=1443839, timecode=531 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=96769, free=1439231, timecode=554 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=101377, free=1434623, timecode=600 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=105985, free=1430015, timecode=624 needsupmix 0 2009-04-21 14:51:50.536 AO: _AddSamples samples=1152 bytes=4608, used=110593, free=1425407, timecode=647 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=115201, free=1420799, timecode=670 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=119809, free=1416191, timecode=693 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=124417, free=1411583, timecode=717 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=129025, free=1406975, timecode=740 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=133633, free=1402367, timecode=763 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=138241, free=1397759, timecode=810 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=142849, free=1393151, timecode=833 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=147457, free=1388543, timecode=856 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=152065, free=1383935, timecode=879 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=156673, free=1379327, timecode=903 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=161281, free=1374719, timecode=926 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=165889, free=1370111, timecode=949 needsupmix 0 2009-04-21 14:51:50.551 AO: _AddSamples samples=1152 bytes=4608, used=170497, free=1365503, timecode=972 needsupmix 0 2009-04-21 14:51:50.567 AO: _AddSamples samples=1152 bytes=4608, used=175105, free=1360895, timecode=1019 needsupmix 0 2009-04-21 14:51:50.567 SetAudiotime set=1240343510.567492, audt=-496 atc=1045 tb=271872 sb=92160 eds=4410000 abps=4 sf=1 2009-04-21 14:51:50.567 GetAudiotime now=1240343510.567492, set=1240343510.567492, ret=0, audt=-496 sf=1 2009-04-21 14:51:50.567 AO: Broadcasting free space avail 2009-04-21 14:51:50.567 GetAudiotime now=1240343510.567492, set=1240343510.567492, ret=0, audt=-496 sf=1 2009-04-21 14:51:50.567 AO: _AddSamples samples=1152 bytes=4608, used=173569, free=1362431, timecode=1042 needsupmix 0 2009-04-21 14:51:50.567 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 1, warpfactor_avg: 1 2009-04-21 14:51:50.567 GetAudiotime now=1240343510.567492, set=1240343510.567492, ret=0, audt=-496 sf=1 2009-04-21 14:51:50.567 AO: _AddSamples samples=1152 bytes=4608, used=178177, free=1357823, timecode=1065 needsupmix 0 2009-04-21 14:51:50.567 GetAudiotime now=1240343510.567492, set=1240343510.567492, ret=0, audt=-496 sf=1 2009-04-21 14:51:50.567 AO: _AddSamples samples=1152 bytes=4608, used=182785, free=1353215, timecode=1089 needsupmix 0 2009-04-21 14:51:50.567 AO: SetEffDsp: 4497943 2009-04-21 14:51:50.567 GetAudiotime now=1240343510.567492, set=1240343510.567492, ret=0, audt=-496 sf=1 2009-04-21 14:51:50.567 AO: _AddSamples samples=1152 bytes=4608, used=187393, free=1348607, timecode=1112 needsupmix 0 2009-04-21 14:51:50.598 GetAudiotime now=1240343510.598742, set=1240343510.567492, ret=31, audt=-496 sf=1 2009-04-21 14:51:50.598 NVP: A/V Divergence: 4.33825, Rate: 4.33825, Warpfactor: 1.0005, warpfactor_avg: 1 2009-04-21 14:51:50.598 SetAudiotime set=1240343510.598742, audt=-442 atc=1137 tb=284160 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.598 GetAudiotime now=1240343510.598742, set=1240343510.598742, ret=0, audt=-442 sf=1 2009-04-21 14:51:50.598 AO: Broadcasting free space avail 2009-04-21 14:51:50.629 SetAudiotime set=1240343510.629993, audt=-408 atc=1137 tb=278016 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.629 GetAudiotime now=1240343510.629993, set=1240343510.629993, ret=0, audt=-408 sf=1 2009-04-21 14:51:50.629 AO: Broadcasting free space avail 2009-04-21 14:51:50.645 GetAudiotime now=1240343510.645618, set=1240343510.629993, ret=15, audt=-408 sf=1 2009-04-21 14:51:50.645 AO: _AddSamples samples=1152 bytes=4608, used=179713, free=1356287, timecode=1135 needsupmix 0 2009-04-21 14:51:50.645 GetAudiotime now=1240343510.645618, set=1240343510.629993, ret=15, audt=-408 sf=1 2009-04-21 14:51:50.645 AO: _AddSamples samples=1152 bytes=4608, used=184321, free=1351679, timecode=1158 needsupmix 0 2009-04-21 14:51:50.661 SetAudiotime set=1240343510.661243, audt=-379 atc=1183 tb=281088 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.661 GetAudiotime now=1240343510.661243, set=1240343510.661243, ret=0, audt=-379 sf=1 2009-04-21 14:51:50.661 AO: Broadcasting free space avail 2009-04-21 14:51:50.676 NVP: Video is 4.33825 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:50.676 GetAudiotime now=1240343510.676868, set=1240343510.661243, ret=15, audt=-379 sf=1 2009-04-21 14:51:50.676 GetAudiotime now=1240343510.676868, set=1240343510.661243, ret=15, audt=-379 sf=1 2009-04-21 14:51:50.676 AO: _AddSamples samples=1152 bytes=4608, used=182785, free=1353215, timecode=1182 needsupmix 0 2009-04-21 14:51:50.676 NVP: A/V Divergence: 7.08991, Rate: 2.75166, Warpfactor: 1.001, warpfactor_avg: 1 2009-04-21 14:51:50.692 SetAudiotime set=1240343510.692493, audt=-346 atc=1207 tb=279552 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.692 GetAudiotime now=1240343510.692493, set=1240343510.692493, ret=0, audt=-346 sf=1 2009-04-21 14:51:50.692 AO: Broadcasting free space avail 2009-04-21 14:51:50.739 SetAudiotime set=1240343510.739369, audt=-312 atc=1207 tb=273408 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.739 GetAudiotime now=1240343510.739369, set=1240343510.739369, ret=0, audt=-312 sf=1 2009-04-21 14:51:50.739 AO: Broadcasting free space avail 2009-04-21 14:51:50.739 NVP: Video is 7.08991 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:50.739 GetAudiotime now=1240343510.739369, set=1240343510.739369, ret=0, audt=-312 sf=1 2009-04-21 14:51:50.754 NVP: A/V Divergence: 9.0113, Rate: 1.92139, Warpfactor: 1.0015, warpfactor_avg: 1 2009-04-21 14:51:50.770 SetAudiotime set=1240343510.770619, audt=-278 atc=1207 tb=267264 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.770 GetAudiotime now=1240343510.770619, set=1240343510.770619, ret=0, audt=-278 sf=1 2009-04-21 14:51:50.770 AO: Broadcasting free space avail 2009-04-21 14:51:50.801 SetAudiotime set=1240343510.801870, audt=-244 atc=1207 tb=261120 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.801 GetAudiotime now=1240343510.801870, set=1240343510.801870, ret=0, audt=-244 sf=1 2009-04-21 14:51:50.801 AO: Broadcasting free space avail 2009-04-21 14:51:50.817 NVP: Video is 9.0113 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:50.817 GetAudiotime now=1240343510.817495, set=1240343510.801870, ret=15, audt=-244 sf=1 2009-04-21 14:51:50.817 GetAudiotime now=1240343510.817495, set=1240343510.801870, ret=15, audt=-244 sf=1 2009-04-21 14:51:50.817 AO: _AddSamples samples=1152 bytes=4608, used=162817, free=1373183, timecode=1228 needsupmix 0 2009-04-21 14:51:50.817 GetAudiotime now=1240343510.817495, set=1240343510.801870, ret=15, audt=-244 sf=1 2009-04-21 14:51:50.817 AO: _AddSamples samples=1152 bytes=4608, used=167425, free=1368575, timecode=1251 needsupmix 0 2009-04-21 14:51:50.817 NVP: A/V Divergence: 10.0777, Rate: 1.06641, Warpfactor: 1.002, warpfactor_avg: 1 2009-04-21 14:51:50.833 SetAudiotime set=1240343510.833120, audt=-192 atc=1276 tb=264192 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.833 GetAudiotime now=1240343510.833120, set=1240343510.833120, ret=0, audt=-192 sf=1 2009-04-21 14:51:50.833 AO: Broadcasting free space avail 2009-04-21 14:51:50.879 SetAudiotime set=1240343510.879996, audt=-158 atc=1276 tb=258048 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.879 GetAudiotime now=1240343510.879996, set=1240343510.879996, ret=0, audt=-158 sf=1 2009-04-21 14:51:50.879 AO: Broadcasting free space avail 2009-04-21 14:51:50.879 NVP: Video is 10.0777 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:50.879 GetAudiotime now=1240343510.879996, set=1240343510.879996, ret=0, audt=-158 sf=1 2009-04-21 14:51:50.879 GetAudiotime now=1240343510.879996, set=1240343510.879996, ret=0, audt=-158 sf=1 2009-04-21 14:51:50.879 AO: _AddSamples samples=1152 bytes=4608, used=159745, free=1376255, timecode=1276 needsupmix 0 2009-04-21 14:51:50.879 NVP: A/V Divergence: 10.6003, Rate: 0.522568, Warpfactor: 1.0025, warpfactor_avg: 1 2009-04-21 14:51:50.895 SetAudiotime set=1240343510.895621, audt=-124 atc=1301 tb=256512 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.895 GetAudiotime now=1240343510.895621, set=1240343510.895621, ret=0, audt=-124 sf=1 2009-04-21 14:51:50.895 AO: Broadcasting free space avail 2009-04-21 14:51:50.942 SetAudiotime set=1240343510.942497, audt=-90 atc=1301 tb=250368 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.942 GetAudiotime now=1240343510.942497, set=1240343510.942497, ret=0, audt=-90 sf=1 2009-04-21 14:51:50.942 AO: Broadcasting free space avail 2009-04-21 14:51:50.942 NVP: Video is 10.6003 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:50.942 GetAudiotime now=1240343510.942497, set=1240343510.942497, ret=0, audt=-90 sf=1 2009-04-21 14:51:50.942 GetAudiotime now=1240343510.942497, set=1240343510.942497, ret=0, audt=-90 sf=1 2009-04-21 14:51:50.942 AO: _AddSamples samples=1152 bytes=4608, used=152065, free=1383935, timecode=1298 needsupmix 0 2009-04-21 14:51:50.942 GetAudiotime now=1240343510.942497, set=1240343510.942497, ret=0, audt=-90 sf=1 2009-04-21 14:51:50.942 AO: _AddSamples samples=1152 bytes=4608, used=156673, free=1379327, timecode=1321 needsupmix 0 2009-04-21 14:51:50.942 NVP: A/V Divergence: 10.73, Rate: 0.129683, Warpfactor: 1.003, warpfactor_avg: 1 2009-04-21 14:51:50.973 SetAudiotime set=1240343510.973747, audt=-62 atc=1346 tb=253440 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:50.973 GetAudiotime now=1240343510.973747, set=1240343510.973747, ret=0, audt=-62 sf=1 2009-04-21 14:51:50.973 AO: Broadcasting free space avail 2009-04-21 14:51:51.020 SetAudiotime set=1240343511.20623, audt=-28 atc=1346 tb=247296 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.020 GetAudiotime now=1240343511.20623, set=1240343511.20623, ret=0, audt=-28 sf=1 2009-04-21 14:51:51.020 AO: Broadcasting free space avail 2009-04-21 14:51:51.020 NVP: Video is 10.73 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.020 GetAudiotime now=1240343511.20623, set=1240343511.20623, ret=0, audt=-28 sf=1 2009-04-21 14:51:51.020 GetAudiotime now=1240343511.20623, set=1240343511.20623, ret=0, audt=-28 sf=1 2009-04-21 14:51:51.020 AO: _AddSamples samples=1152 bytes=4608, used=148993, free=1387007, timecode=1344 needsupmix 0 2009-04-21 14:51:51.020 NVP: A/V Divergence: 10.61, Rate: -0.120002, Warpfactor: 1.0035, warpfactor_avg: 1 2009-04-21 14:51:51.036 SetAudiotime set=1240343511.36248, audt=4 atc=1369 tb=245760 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.036 GetAudiotime now=1240343511.36248, set=1240343511.36248, ret=0, audt=4 sf=1 2009-04-21 14:51:51.036 AO: Broadcasting free space avail 2009-04-21 14:51:51.083 NVP: Video is 10.61 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.083 GetAudiotime now=1240343511.83123, set=1240343511.36248, ret=46, audt=4 sf=1 2009-04-21 14:51:51.083 GetAudiotime now=1240343511.83123, set=1240343511.36248, ret=46, audt=4 sf=1 2009-04-21 14:51:51.083 AO: _AddSamples samples=1152 bytes=4608, used=147457, free=1388543, timecode=1368 needsupmix 0 2009-04-21 14:51:51.083 GetAudiotime now=1240343511.83123, set=1240343511.36248, ret=46, audt=4 sf=1 2009-04-21 14:51:51.083 AO: _AddSamples samples=1152 bytes=4608, used=152065, free=1383935, timecode=1391 needsupmix 0 2009-04-21 14:51:51.083 NVP: A/V Divergence: 10.1903, Rate: -0.419679, Warpfactor: 1.00401, warpfactor_avg: 1 2009-04-21 14:51:51.083 SetAudiotime set=1240343511.83123, audt=33 atc=1416 tb=248832 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.083 GetAudiotime now=1240343511.83123, set=1240343511.83123, ret=0, audt=33 sf=1 2009-04-21 14:51:51.083 AO: Broadcasting free space avail 2009-04-21 14:51:51.114 SetAudiotime set=1240343511.114374, audt=68 atc=1416 tb=242688 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.114 GetAudiotime now=1240343511.114374, set=1240343511.114374, ret=0, audt=68 sf=1 2009-04-21 14:51:51.114 AO: Broadcasting free space avail 2009-04-21 14:51:51.145 NVP: Video is 10.1903 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.145 GetAudiotime now=1240343511.145624, set=1240343511.114374, ret=31, audt=68 sf=1 2009-04-21 14:51:51.145 NVP: A/V Divergence: 9.75562, Rate: -0.434664, Warpfactor: 1.00451, warpfactor_avg: 1 2009-04-21 14:51:51.161 SetAudiotime set=1240343511.161249, audt=102 atc=1416 tb=236544 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.161 GetAudiotime now=1240343511.161249, set=1240343511.161249, ret=0, audt=102 sf=1 2009-04-21 14:51:51.161 AO: Broadcasting free space avail 2009-04-21 14:51:51.176 SetAudiotime set=1240343511.176875, audt=136 atc=1416 tb=230400 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.176 GetAudiotime now=1240343511.176875, set=1240343511.176875, ret=0, audt=136 sf=1 2009-04-21 14:51:51.176 AO: Broadcasting free space avail 2009-04-21 14:51:51.208 NVP: Video is 9.75562 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.208 GetAudiotime now=1240343511.208125, set=1240343511.176875, ret=31, audt=136 sf=1 2009-04-21 14:51:51.208 GetAudiotime now=1240343511.208125, set=1240343511.176875, ret=31, audt=136 sf=1 2009-04-21 14:51:51.208 AO: _AddSamples samples=1152 bytes=4608, used=132097, free=1403903, timecode=1437 needsupmix 0 2009-04-21 14:51:51.208 NVP: A/V Divergence: 9.16739, Rate: -0.588234, Warpfactor: 1.00501, warpfactor_avg: 1 2009-04-21 14:51:51.223 SetAudiotime set=1240343511.223750, audt=190 atc=1462 tb=228864 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.223 GetAudiotime now=1240343511.223750, set=1240343511.223750, ret=0, audt=190 sf=1 2009-04-21 14:51:51.223 AO: Broadcasting free space avail 2009-04-21 14:51:51.255 SetAudiotime set=1240343511.255001, audt=225 atc=1462 tb=222720 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.255 GetAudiotime now=1240343511.255001, set=1240343511.255001, ret=0, audt=225 sf=1 2009-04-21 14:51:51.255 AO: Broadcasting free space avail 2009-04-21 14:51:51.286 NVP: Video is 9.16739 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.286 GetAudiotime now=1240343511.286251, set=1240343511.255001, ret=31, audt=225 sf=1 2009-04-21 14:51:51.286 GetAudiotime now=1240343511.286251, set=1240343511.255001, ret=31, audt=225 sf=1 2009-04-21 14:51:51.286 AO: _AddSamples samples=1152 bytes=4608, used=124417, free=1411583, timecode=1461 needsupmix 0 2009-04-21 14:51:51.286 GetAudiotime now=1240343511.286251, set=1240343511.255001, ret=31, audt=225 sf=1 2009-04-21 14:51:51.286 AO: _AddSamples samples=1152 bytes=4608, used=129025, free=1406975, timecode=1484 needsupmix 0 2009-04-21 14:51:51.286 NVP: A/V Divergence: 8.31412, Rate: -0.853264, Warpfactor: 1.00551, warpfactor_avg: 1 2009-04-21 14:51:51.301 SetAudiotime set=1240343511.301876, audt=255 atc=1509 tb=225792 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.301 GetAudiotime now=1240343511.301876, set=1240343511.301876, ret=0, audt=255 sf=1 2009-04-21 14:51:51.301 AO: Broadcasting free space avail 2009-04-21 14:51:51.317 SetAudiotime set=1240343511.317501, audt=289 atc=1509 tb=219648 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.317 GetAudiotime now=1240343511.317501, set=1240343511.317501, ret=0, audt=289 sf=1 2009-04-21 14:51:51.317 AO: Broadcasting free space avail 2009-04-21 14:51:51.348 NVP: Video is 8.31412 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.348 GetAudiotime now=1240343511.348752, set=1240343511.317501, ret=31, audt=289 sf=1 2009-04-21 14:51:51.348 GetAudiotime now=1240343511.348752, set=1240343511.317501, ret=31, audt=289 sf=1 2009-04-21 14:51:51.348 AO: _AddSamples samples=1152 bytes=4608, used=121345, free=1414655, timecode=1507 needsupmix 0 2009-04-21 14:51:51.348 NVP: A/V Divergence: 7.44189, Rate: -0.872235, Warpfactor: 1.00602, warpfactor_avg: 1 2009-04-21 14:51:51.364 SetAudiotime set=1240343511.364377, audt=320 atc=1532 tb=218112 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.364 GetAudiotime now=1240343511.364377, set=1240343511.364377, ret=0, audt=320 sf=1 2009-04-21 14:51:51.364 AO: Broadcasting free space avail 2009-04-21 14:51:51.395 SetAudiotime set=1240343511.395627, audt=354 atc=1532 tb=211968 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.395 GetAudiotime now=1240343511.395627, set=1240343511.395627, ret=0, audt=354 sf=1 2009-04-21 14:51:51.395 AO: Broadcasting free space avail 2009-04-21 14:51:51.411 NVP: Video is 7.44189 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.411 GetAudiotime now=1240343511.411253, set=1240343511.395627, ret=15, audt=354 sf=1 2009-04-21 14:51:51.411 GetAudiotime now=1240343511.411253, set=1240343511.395627, ret=15, audt=354 sf=1 2009-04-21 14:51:51.411 AO: _AddSamples samples=1152 bytes=4608, used=113665, free=1422335, timecode=1530 needsupmix 0 2009-04-21 14:51:51.411 GetAudiotime now=1240343511.411253, set=1240343511.395627, ret=15, audt=354 sf=1 2009-04-21 14:51:51.411 AO: _AddSamples samples=1152 bytes=4608, used=118273, free=1417727, timecode=1554 needsupmix 0 2009-04-21 14:51:51.411 NVP: A/V Divergence: 6.67533, Rate: -0.766559, Warpfactor: 1.00652, warpfactor_avg: 1 2009-04-21 14:51:51.442 SetAudiotime set=1240343511.442503, audt=384 atc=1579 tb=215040 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.442 GetAudiotime now=1240343511.442503, set=1240343511.442503, ret=0, audt=384 sf=1 2009-04-21 14:51:51.442 AO: Broadcasting free space avail 2009-04-21 14:51:51.458 SetAudiotime set=1240343511.458128, audt=418 atc=1579 tb=208896 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.458 GetAudiotime now=1240343511.458128, set=1240343511.458128, ret=0, audt=418 sf=1 2009-04-21 14:51:51.458 AO: Broadcasting free space avail 2009-04-21 14:51:51.473 NVP: Video is 6.67533 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.473 GetAudiotime now=1240343511.473753, set=1240343511.458128, ret=15, audt=418 sf=1 2009-04-21 14:51:51.473 GetAudiotime now=1240343511.473753, set=1240343511.458128, ret=15, audt=418 sf=1 2009-04-21 14:51:51.473 AO: _AddSamples samples=1152 bytes=4608, used=110593, free=1425407, timecode=1577 needsupmix 0 2009-04-21 14:51:51.473 NVP: A/V Divergence: 5.86813, Rate: -0.807199, Warpfactor: 1.00702, warpfactor_avg: 1 2009-04-21 14:51:51.505 SetAudiotime set=1240343511.505004, audt=450 atc=1602 tb=207360 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.505 GetAudiotime now=1240343511.505004, set=1240343511.505004, ret=0, audt=450 sf=1 2009-04-21 14:51:51.505 AO: Broadcasting free space avail 2009-04-21 14:51:51.536 SetAudiotime set=1240343511.536254, audt=484 atc=1602 tb=201216 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.536 GetAudiotime now=1240343511.536254, set=1240343511.536254, ret=0, audt=484 sf=1 2009-04-21 14:51:51.536 AO: Broadcasting free space avail 2009-04-21 14:51:51.551 NVP: Video is 5.86813 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.551 GetAudiotime now=1240343511.551879, set=1240343511.536254, ret=15, audt=484 sf=1 2009-04-21 14:51:51.551 GetAudiotime now=1240343511.551879, set=1240343511.536254, ret=15, audt=484 sf=1 2009-04-21 14:51:51.551 AO: _AddSamples samples=1152 bytes=4608, used=102913, free=1433087, timecode=1600 needsupmix 0 2009-04-21 14:51:51.551 NVP: A/V Divergence: 5.01549, Rate: -0.852634, Warpfactor: 1.00753, warpfactor_avg: 1 2009-04-21 14:51:51.583 SetAudiotime set=1240343511.583130, audt=516 atc=1625 tb=199680 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.583 GetAudiotime now=1240343511.583130, set=1240343511.583130, ret=0, audt=516 sf=1 2009-04-21 14:51:51.583 AO: Broadcasting free space avail 2009-04-21 14:51:51.598 SetAudiotime set=1240343511.598755, audt=550 atc=1625 tb=193536 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.598 GetAudiotime now=1240343511.598755, set=1240343511.598755, ret=0, audt=550 sf=1 2009-04-21 14:51:51.598 AO: Broadcasting free space avail 2009-04-21 14:51:51.614 NVP: Video is 5.01549 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.614 GetAudiotime now=1240343511.614380, set=1240343511.598755, ret=15, audt=550 sf=1 2009-04-21 14:51:51.614 GetAudiotime now=1240343511.614380, set=1240343511.598755, ret=15, audt=550 sf=1 2009-04-21 14:51:51.614 AO: _AddSamples samples=1152 bytes=4608, used=95233, free=1440767, timecode=1647 needsupmix 0 2009-04-21 14:51:51.614 NVP: A/V Divergence: 4.13625, Rate: -0.879248, Warpfactor: 1.00803, warpfactor_avg: 1 2009-04-21 14:51:51.630 SetAudiotime set=1240343511.630005, audt=605 atc=1672 tb=192000 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.630 GetAudiotime now=1240343511.630005, set=1240343511.630005, ret=0, audt=605 sf=1 2009-04-21 14:51:51.630 AO: Broadcasting free space avail 2009-04-21 14:51:51.676 SetAudiotime set=1240343511.676881, audt=639 atc=1672 tb=185856 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.676 GetAudiotime now=1240343511.676881, set=1240343511.676881, ret=0, audt=639 sf=1 2009-04-21 14:51:51.676 AO: Broadcasting free space avail 2009-04-21 14:51:51.676 NVP: Video is 4.13625 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.676 GetAudiotime now=1240343511.676881, set=1240343511.676881, ret=0, audt=639 sf=1 2009-04-21 14:51:51.676 GetAudiotime now=1240343511.676881, set=1240343511.676881, ret=0, audt=639 sf=1 2009-04-21 14:51:51.676 AO: _AddSamples samples=1152 bytes=4608, used=87553, free=1448447, timecode=1670 needsupmix 0 2009-04-21 14:51:51.676 NVP: A/V Divergence: 3.1696, Rate: -0.966643, Warpfactor: 1.00853, warpfactor_avg: 1 2009-04-21 14:51:51.708 SetAudiotime set=1240343511.708131, audt=671 atc=1695 tb=184320 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.708 GetAudiotime now=1240343511.708131, set=1240343511.708131, ret=0, audt=671 sf=1 2009-04-21 14:51:51.708 AO: Broadcasting free space avail 2009-04-21 14:51:51.739 SetAudiotime set=1240343511.739382, audt=705 atc=1695 tb=178176 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.739 GetAudiotime now=1240343511.739382, set=1240343511.739382, ret=0, audt=705 sf=1 2009-04-21 14:51:51.739 AO: Broadcasting free space avail 2009-04-21 14:51:51.755 NVP: Video is 3.1696 frames ahead of audio, doubling video frame interval to slow down. 2009-04-21 14:51:51.755 GetAudiotime now=1240343511.755007, set=1240343511.739382, ret=15, audt=705 sf=1 2009-04-21 14:51:51.755 GetAudiotime now=1240343511.755007, set=1240343511.739382, ret=15, audt=705 sf=1 2009-04-21 14:51:51.755 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=1693 needsupmix 0 2009-04-21 14:51:51.755 GetAudiotime now=1240343511.755007, set=1240343511.739382, ret=15, audt=705 sf=1 2009-04-21 14:51:51.755 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=1716 needsupmix 0 2009-04-21 14:51:51.755 NVP: A/V Divergence: 2.09246, Rate: -1.07714, Warpfactor: 1.00813, warpfactor_avg: 1 2009-04-21 14:51:51.770 SetAudiotime set=1240343511.770632, audt=734 atc=1741 tb=181248 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.770 GetAudiotime now=1240343511.770632, set=1240343511.770632, ret=0, audt=734 sf=1 2009-04-21 14:51:51.770 AO: Broadcasting free space avail 2009-04-21 14:51:51.817 GetAudiotime now=1240343511.817508, set=1240343511.770632, ret=46, audt=734 sf=1 2009-04-21 14:51:51.817 GetAudiotime now=1240343511.817508, set=1240343511.770632, ret=46, audt=734 sf=1 2009-04-21 14:51:51.817 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=1740 needsupmix 0 2009-04-21 14:51:51.817 NVP: A/V Divergence: 1.0823, Rate: -1.01016, Warpfactor: 1.00762, warpfactor_avg: 1.00001 2009-04-21 14:51:51.817 SetAudiotime set=1240343511.817508, audt=767 atc=1765 tb=179712 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.817 GetAudiotime now=1240343511.817508, set=1240343511.817508, ret=0, audt=767 sf=1 2009-04-21 14:51:51.817 AO: Broadcasting free space avail 2009-04-21 14:51:51.848 SetAudiotime set=1240343511.848758, audt=801 atc=1765 tb=173568 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.848 GetAudiotime now=1240343511.848758, set=1240343511.848758, ret=0, audt=801 sf=1 2009-04-21 14:51:51.848 AO: Broadcasting free space avail 2009-04-21 14:51:51.848 GetAudiotime now=1240343511.848758, set=1240343511.848758, ret=0, audt=801 sf=1 2009-04-21 14:51:51.848 GetAudiotime now=1240343511.848758, set=1240343511.848758, ret=0, audt=801 sf=1 2009-04-21 14:51:51.848 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=1763 needsupmix 0 2009-04-21 14:51:51.848 GetAudiotime now=1240343511.848758, set=1240343511.848758, ret=0, audt=801 sf=1 2009-04-21 14:51:51.848 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=1786 needsupmix 0 2009-04-21 14:51:51.848 NVP: A/V Divergence: 0.414614, Rate: -0.667686, Warpfactor: 1.00712, warpfactor_avg: 1.00001 2009-04-21 14:51:51.880 GetAudiotime now=1240343511.880009, set=1240343511.848758, ret=31, audt=801 sf=1 2009-04-21 14:51:51.880 GetAudiotime now=1240343511.880009, set=1240343511.848758, ret=31, audt=801 sf=1 2009-04-21 14:51:51.880 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=1809 needsupmix 0 2009-04-21 14:51:51.880 NVP: A/V Divergence: -0.0636576, Rate: -0.478271, Warpfactor: 1.00662, warpfactor_avg: 1.00001 2009-04-21 14:51:51.880 SetAudiotime set=1240343511.880009, audt=827 atc=1834 tb=181248 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.880 GetAudiotime now=1240343511.880009, set=1240343511.880009, ret=0, audt=827 sf=1 2009-04-21 14:51:51.880 AO: Broadcasting free space avail 2009-04-21 14:51:51.911 GetAudiotime now=1240343511.911259, set=1240343511.880009, ret=31, audt=827 sf=1 2009-04-21 14:51:51.911 GetAudiotime now=1240343511.911259, set=1240343511.880009, ret=31, audt=827 sf=1 2009-04-21 14:51:51.911 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=1856 needsupmix 0 2009-04-21 14:51:51.911 SetAudiotime set=1240343511.911259, audt=883 atc=1881 tb=179712 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.911 GetAudiotime now=1240343511.911259, set=1240343511.911259, ret=0, audt=883 sf=1 2009-04-21 14:51:51.911 AO: Broadcasting free space avail 2009-04-21 14:51:51.911 NVP: A/V Divergence: -0.369927, Rate: -0.30627, Warpfactor: 1.00611, warpfactor_avg: 1.00001 2009-04-21 14:51:51.942 GetAudiotime now=1240343511.942509, set=1240343511.911259, ret=31, audt=883 sf=1 2009-04-21 14:51:51.942 GetAudiotime now=1240343511.942509, set=1240343511.911259, ret=31, audt=883 sf=1 2009-04-21 14:51:51.942 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=1879 needsupmix 0 2009-04-21 14:51:51.958 NVP: A/V Divergence: -0.771953, Rate: -0.402026, Warpfactor: 1.00561, warpfactor_avg: 1.00001 2009-04-21 14:51:51.958 SetAudiotime set=1240343511.958135, audt=914 atc=1904 tb=178176 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.958 GetAudiotime now=1240343511.958135, set=1240343511.958135, ret=0, audt=914 sf=1 2009-04-21 14:51:51.958 AO: Broadcasting free space avail 2009-04-21 14:51:51.973 GetAudiotime now=1240343511.973760, set=1240343511.958135, ret=15, audt=914 sf=1 2009-04-21 14:51:51.973 GetAudiotime now=1240343511.973760, set=1240343511.958135, ret=15, audt=914 sf=1 2009-04-21 14:51:51.973 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=1902 needsupmix 0 2009-04-21 14:51:51.973 GetAudiotime now=1240343511.973760, set=1240343511.958135, ret=15, audt=914 sf=1 2009-04-21 14:51:51.973 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=1925 needsupmix 0 2009-04-21 14:51:51.989 NVP: A/V Divergence: -0.931098, Rate: -0.159144, Warpfactor: 1.00511, warpfactor_avg: 1.00001 2009-04-21 14:51:51.989 SetAudiotime set=1240343511.989385, audt=943 atc=1950 tb=181248 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:51.989 GetAudiotime now=1240343511.989385, set=1240343511.989385, ret=0, audt=943 sf=1 2009-04-21 14:51:51.989 AO: Broadcasting free space avail 2009-04-21 14:51:52.020 GetAudiotime now=1240343512.20635, set=1240343511.989385, ret=32, audt=943 sf=1 2009-04-21 14:51:52.020 GetAudiotime now=1240343512.20635, set=1240343511.989385, ret=32, audt=943 sf=1 2009-04-21 14:51:52.020 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=1949 needsupmix 0 2009-04-21 14:51:52.020 NVP: A/V Divergence: -1.14788, Rate: -0.216778, Warpfactor: 1.0046, warpfactor_avg: 1.00001 2009-04-21 14:51:52.020 SetAudiotime set=1240343512.20635, audt=976 atc=1974 tb=179712 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:52.020 GetAudiotime now=1240343512.20635, set=1240343512.20635, ret=0, audt=976 sf=1 2009-04-21 14:51:52.020 AO: Broadcasting free space avail 2009-04-21 14:51:52.051 GetAudiotime now=1240343512.51886, set=1240343512.20635, ret=31, audt=976 sf=1 2009-04-21 14:51:52.051 GetAudiotime now=1240343512.51886, set=1240343512.20635, ret=31, audt=976 sf=1 2009-04-21 14:51:52.051 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=1972 needsupmix 0 2009-04-21 14:51:52.051 NVP: A/V Divergence: -1.30297, Rate: -0.155098, Warpfactor: 1.0041, warpfactor_avg: 1.00001 2009-04-21 14:51:52.051 SetAudiotime set=1240343512.51886, audt=1007 atc=1997 tb=178176 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:52.051 GetAudiotime now=1240343512.51886, set=1240343512.51886, ret=0, audt=1007 sf=1 2009-04-21 14:51:52.051 AO: Broadcasting free space avail 2009-04-21 14:51:52.083 GetAudiotime now=1240343512.83136, set=1240343512.51886, ret=31, audt=1007 sf=1 2009-04-21 14:51:52.083 GetAudiotime now=1240343512.83136, set=1240343512.51886, ret=31, audt=1007 sf=1 2009-04-21 14:51:52.083 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=1995 needsupmix 0 2009-04-21 14:51:52.083 GetAudiotime now=1240343512.83136, set=1240343512.51886, ret=31, audt=1007 sf=1 2009-04-21 14:51:52.083 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=2018 needsupmix 0 2009-04-21 14:51:52.083 NVP: A/V Divergence: -1.39681, Rate: -0.093838, Warpfactor: 1.0036, warpfactor_avg: 1.00001 2009-04-21 14:51:52.098 SetAudiotime set=1240343512.98761, audt=1036 atc=2043 tb=181248 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:52.098 GetAudiotime now=1240343512.98761, set=1240343512.98761, ret=0, audt=1036 sf=1 2009-04-21 14:51:52.098 AO: Broadcasting free space avail 2009-04-21 14:51:52.114 GetAudiotime now=1240343512.114387, set=1240343512.98761, ret=15, audt=1036 sf=1 2009-04-21 14:51:52.114 NVP: A/V Divergence: -1.31733, Rate: 0.0794821, Warpfactor: 1.0031, warpfactor_avg: 1.00001 2009-04-21 14:51:52.130 SetAudiotime set=1240343512.130012, audt=1070 atc=2043 tb=175104 sb=92160 eds=4497943 abps=4 sf=1 2009-04-21 14:51:52.130 GetAudiotime now=1240343512.130012, set=1240343512.130012, ret=0, audt=1070 sf=1 2009-04-21 14:51:52.130 AO: Broadcasting free space avail 2009-04-21 14:51:52.145 GetAudiotime now=1240343512.145637, set=1240343512.130012, ret=15, audt=1070 sf=1 2009-04-21 14:51:52.145 GetAudiotime now=1240343512.145637, set=1240343512.130012, ret=15, audt=1070 sf=1 2009-04-21 14:51:52.145 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2065 needsupmix 0 2009-04-21 14:51:52.145 GetAudiotime now=1240343512.145637, set=1240343512.130012, ret=15, audt=1070 sf=1 2009-04-21 14:51:52.145 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2088 needsupmix 0 2009-04-21 14:51:52.145 AO: SetEffDsp: 4454325 2009-04-21 14:51:52.145 NVP: A/V Divergence: -1.26521, Rate: 0.0521189, Warpfactor: 1.0026, warpfactor_avg: 1.00001 2009-04-21 14:51:52.161 SetAudiotime set=1240343512.161262, audt=1113 atc=2113 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.161 GetAudiotime now=1240343512.161262, set=1240343512.161262, ret=0, audt=1113 sf=1 2009-04-21 14:51:52.161 AO: Broadcasting free space avail 2009-04-21 14:51:52.176 GetAudiotime now=1240343512.176887, set=1240343512.161262, ret=15, audt=1113 sf=1 2009-04-21 14:51:52.176 GetAudiotime now=1240343512.176887, set=1240343512.161262, ret=15, audt=1113 sf=1 2009-04-21 14:51:52.176 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2111 needsupmix 0 2009-04-21 14:51:52.192 NVP: A/V Divergence: -1.29356, Rate: -0.0283522, Warpfactor: 1.00209, warpfactor_avg: 1.00001 2009-04-21 14:51:52.192 SetAudiotime set=1240343512.192513, audt=1145 atc=2136 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.192 GetAudiotime now=1240343512.192513, set=1240343512.192513, ret=0, audt=1145 sf=1 2009-04-21 14:51:52.192 AO: Broadcasting free space avail 2009-04-21 14:51:52.208 GetAudiotime now=1240343512.208138, set=1240343512.192513, ret=15, audt=1145 sf=1 2009-04-21 14:51:52.208 GetAudiotime now=1240343512.208138, set=1240343512.192513, ret=15, audt=1145 sf=1 2009-04-21 14:51:52.208 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2135 needsupmix 0 2009-04-21 14:51:52.208 GetAudiotime now=1240343512.208138, set=1240343512.192513, ret=15, audt=1145 sf=1 2009-04-21 14:51:52.208 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=2158 needsupmix 0 2009-04-21 14:51:52.223 NVP: A/V Divergence: -1.30732, Rate: -0.0137565, Warpfactor: 1.00159, warpfactor_avg: 1.00001 2009-04-21 14:51:52.239 SetAudiotime set=1240343512.239388, audt=1175 atc=2183 tb=179712 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.239 GetAudiotime now=1240343512.239388, set=1240343512.239388, ret=0, audt=1175 sf=1 2009-04-21 14:51:52.239 AO: Broadcasting free space avail 2009-04-21 14:51:52.255 GetAudiotime now=1240343512.255013, set=1240343512.239388, ret=15, audt=1175 sf=1 2009-04-21 14:51:52.255 GetAudiotime now=1240343512.255013, set=1240343512.239388, ret=15, audt=1175 sf=1 2009-04-21 14:51:52.255 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2181 needsupmix 0 2009-04-21 14:51:52.255 NVP: A/V Divergence: -1.28769, Rate: 0.0196308, Warpfactor: 1.00109, warpfactor_avg: 1.00001 2009-04-21 14:51:52.270 SetAudiotime set=1240343512.270639, audt=1206 atc=2206 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.270 GetAudiotime now=1240343512.270639, set=1240343512.270639, ret=0, audt=1206 sf=1 2009-04-21 14:51:52.270 AO: Broadcasting free space avail 2009-04-21 14:51:52.286 GetAudiotime now=1240343512.286264, set=1240343512.270639, ret=15, audt=1206 sf=1 2009-04-21 14:51:52.286 GetAudiotime now=1240343512.286264, set=1240343512.270639, ret=15, audt=1206 sf=1 2009-04-21 14:51:52.286 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2204 needsupmix 0 2009-04-21 14:51:52.286 NVP: A/V Divergence: -1.25796, Rate: 0.0297309, Warpfactor: 1.00059, warpfactor_avg: 1.00001 2009-04-21 14:51:52.301 SetAudiotime set=1240343512.301889, audt=1238 atc=2229 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.301 GetAudiotime now=1240343512.301889, set=1240343512.301889, ret=0, audt=1238 sf=1 2009-04-21 14:51:52.301 AO: Broadcasting free space avail 2009-04-21 14:51:52.317 GetAudiotime now=1240343512.317514, set=1240343512.301889, ret=15, audt=1238 sf=1 2009-04-21 14:51:52.317 GetAudiotime now=1240343512.317514, set=1240343512.301889, ret=15, audt=1238 sf=1 2009-04-21 14:51:52.317 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2228 needsupmix 0 2009-04-21 14:51:52.317 NVP: A/V Divergence: -1.22817, Rate: 0.0297908, Warpfactor: 1.00009, warpfactor_avg: 1.00001 2009-04-21 14:51:52.333 SetAudiotime set=1240343512.333139, audt=1271 atc=2253 tb=175104 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.333 GetAudiotime now=1240343512.333139, set=1240343512.333139, ret=0, audt=1271 sf=1 2009-04-21 14:51:52.333 AO: Broadcasting free space avail 2009-04-21 14:51:52.348 GetAudiotime now=1240343512.348765, set=1240343512.333139, ret=15, audt=1271 sf=1 2009-04-21 14:51:52.348 GetAudiotime now=1240343512.348765, set=1240343512.333139, ret=15, audt=1271 sf=1 2009-04-21 14:51:52.348 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2274 needsupmix 0 2009-04-21 14:51:52.348 NVP: A/V Divergence: -1.19835, Rate: 0.0298208, Warpfactor: 0.999592, warpfactor_avg: 1.00001 2009-04-21 14:51:52.380 SetAudiotime set=1240343512.380015, audt=1325 atc=2299 tb=173568 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.380 GetAudiotime now=1240343512.380015, set=1240343512.380015, ret=0, audt=1325 sf=1 2009-04-21 14:51:52.380 AO: Broadcasting free space avail 2009-04-21 14:51:52.380 GetAudiotime now=1240343512.380015, set=1240343512.380015, ret=0, audt=1325 sf=1 2009-04-21 14:51:52.380 GetAudiotime now=1240343512.380015, set=1240343512.380015, ret=0, audt=1325 sf=1 2009-04-21 14:51:52.380 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=2297 needsupmix 0 2009-04-21 14:51:52.380 GetAudiotime now=1240343512.380015, set=1240343512.380015, ret=0, audt=1325 sf=1 2009-04-21 14:51:52.380 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2321 needsupmix 0 2009-04-21 14:51:52.380 NVP: A/V Divergence: -1.22094, Rate: -0.0225979, Warpfactor: 0.999092, warpfactor_avg: 1.00001 2009-04-21 14:51:52.395 SetAudiotime set=1240343512.395640, audt=1355 atc=2346 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.395 GetAudiotime now=1240343512.395640, set=1240343512.395640, ret=0, audt=1355 sf=1 2009-04-21 14:51:52.395 AO: Broadcasting free space avail 2009-04-21 14:51:52.411 GetAudiotime now=1240343512.411265, set=1240343512.395640, ret=15, audt=1355 sf=1 2009-04-21 14:51:52.411 GetAudiotime now=1240343512.411265, set=1240343512.395640, ret=15, audt=1355 sf=1 2009-04-21 14:51:52.411 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2344 needsupmix 0 2009-04-21 14:51:52.411 NVP: A/V Divergence: -1.32779, Rate: -0.106845, Warpfactor: 0.998592, warpfactor_avg: 1.00001 2009-04-21 14:51:52.442 SetAudiotime set=1240343512.442516, audt=1387 atc=2369 tb=175104 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.442 GetAudiotime now=1240343512.442516, set=1240343512.442516, ret=0, audt=1387 sf=1 2009-04-21 14:51:52.442 AO: Broadcasting free space avail 2009-04-21 14:51:52.442 GetAudiotime now=1240343512.442516, set=1240343512.442516, ret=0, audt=1387 sf=1 2009-04-21 14:51:52.442 GetAudiotime now=1240343512.442516, set=1240343512.442516, ret=0, audt=1387 sf=1 2009-04-21 14:51:52.442 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2367 needsupmix 0 2009-04-21 14:51:52.442 GetAudiotime now=1240343512.442516, set=1240343512.442516, ret=0, audt=1387 sf=1 2009-04-21 14:51:52.442 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2390 needsupmix 0 2009-04-21 14:51:52.442 NVP: A/V Divergence: -1.28056, Rate: 0.0472337, Warpfactor: 0.998093, warpfactor_avg: 1.00001 2009-04-21 14:51:52.473 SetAudiotime set=1240343512.473766, audt=1415 atc=2415 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.473 GetAudiotime now=1240343512.473766, set=1240343512.473766, ret=0, audt=1415 sf=1 2009-04-21 14:51:52.473 AO: Broadcasting free space avail 2009-04-21 14:51:52.473 GetAudiotime now=1240343512.473766, set=1240343512.473766, ret=0, audt=1415 sf=1 2009-04-21 14:51:52.473 GetAudiotime now=1240343512.473766, set=1240343512.473766, ret=0, audt=1415 sf=1 2009-04-21 14:51:52.473 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2414 needsupmix 0 2009-04-21 14:51:52.489 NVP: A/V Divergence: -1.20767, Rate: 0.0728886, Warpfactor: 0.997594, warpfactor_avg: 1.00001 2009-04-21 14:51:52.520 SetAudiotime set=1240343512.520642, audt=1448 atc=2439 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.520 GetAudiotime now=1240343512.520642, set=1240343512.520642, ret=0, audt=1448 sf=1 2009-04-21 14:51:52.520 AO: Broadcasting free space avail 2009-04-21 14:51:52.520 GetAudiotime now=1240343512.520642, set=1240343512.520642, ret=0, audt=1448 sf=1 2009-04-21 14:51:52.520 GetAudiotime now=1240343512.520642, set=1240343512.520642, ret=0, audt=1448 sf=1 2009-04-21 14:51:52.520 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2437 needsupmix 0 2009-04-21 14:51:52.520 NVP: A/V Divergence: -1.14551, Rate: 0.0621591, Warpfactor: 0.997095, warpfactor_avg: 1.00001 2009-04-21 14:51:52.536 SetAudiotime set=1240343512.536267, audt=1480 atc=2462 tb=175104 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.536 GetAudiotime now=1240343512.536267, set=1240343512.536267, ret=0, audt=1480 sf=1 2009-04-21 14:51:52.536 AO: Broadcasting free space avail 2009-04-21 14:51:52.551 GetAudiotime now=1240343512.551892, set=1240343512.536267, ret=15, audt=1480 sf=1 2009-04-21 14:51:52.551 GetAudiotime now=1240343512.551892, set=1240343512.536267, ret=15, audt=1480 sf=1 2009-04-21 14:51:52.551 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2483 needsupmix 0 2009-04-21 14:51:52.551 NVP: A/V Divergence: -1.20377, Rate: -0.0582629, Warpfactor: 0.996597, warpfactor_avg: 1.00001 2009-04-21 14:51:52.583 GetAudiotime now=1240343512.583143, set=1240343512.536267, ret=46, audt=1480 sf=1 2009-04-21 14:51:52.583 GetAudiotime now=1240343512.583143, set=1240343512.536267, ret=46, audt=1480 sf=1 2009-04-21 14:51:52.583 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2507 needsupmix 0 2009-04-21 14:51:52.583 SetAudiotime set=1240343512.583143, audt=1532 atc=2532 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.583 GetAudiotime now=1240343512.583143, set=1240343512.583143, ret=0, audt=1532 sf=1 2009-04-21 14:51:52.583 AO: Broadcasting free space avail 2009-04-21 14:51:52.583 NVP: A/V Divergence: -1.23248, Rate: -0.0287119, Warpfactor: 0.996098, warpfactor_avg: 1.00001 2009-04-21 14:51:52.614 SetAudiotime set=1240343512.614393, audt=1567 atc=2532 tb=172032 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.614 GetAudiotime now=1240343512.614393, set=1240343512.614393, ret=0, audt=1567 sf=1 2009-04-21 14:51:52.614 AO: Broadcasting free space avail 2009-04-21 14:51:52.614 GetAudiotime now=1240343512.614393, set=1240343512.614393, ret=0, audt=1567 sf=1 2009-04-21 14:51:52.614 GetAudiotime now=1240343512.614393, set=1240343512.614393, ret=0, audt=1567 sf=1 2009-04-21 14:51:52.614 AO: _AddSamples samples=1152 bytes=4608, used=73729, free=1462271, timecode=2530 needsupmix 0 2009-04-21 14:51:52.614 GetAudiotime now=1240343512.614393, set=1240343512.614393, ret=0, audt=1567 sf=1 2009-04-21 14:51:52.614 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2553 needsupmix 0 2009-04-21 14:51:52.614 NVP: A/V Divergence: -1.30648, Rate: -0.0739975, Warpfactor: 0.9956, warpfactor_avg: 1.00001 2009-04-21 14:51:52.645 SetAudiotime set=1240343512.645643, audt=1596 atc=2578 tb=175104 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.645 GetAudiotime now=1240343512.645643, set=1240343512.645643, ret=0, audt=1596 sf=1 2009-04-21 14:51:52.645 AO: Broadcasting free space avail 2009-04-21 14:51:52.645 GetAudiotime now=1240343512.645643, set=1240343512.645643, ret=0, audt=1596 sf=1 2009-04-21 14:51:52.645 GetAudiotime now=1240343512.645643, set=1240343512.645643, ret=0, audt=1596 sf=1 2009-04-21 14:51:52.645 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2576 needsupmix 0 2009-04-21 14:51:52.645 NVP: A/V Divergence: -1.33201, Rate: -0.025535, Warpfactor: 0.995102, warpfactor_avg: 1.00001 2009-04-21 14:51:52.676 GetAudiotime now=1240343512.676894, set=1240343512.645643, ret=31, audt=1596 sf=1 2009-04-21 14:51:52.676 GetAudiotime now=1240343512.676894, set=1240343512.645643, ret=31, audt=1596 sf=1 2009-04-21 14:51:52.676 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2600 needsupmix 0 2009-04-21 14:51:52.676 GetAudiotime now=1240343512.676894, set=1240343512.645643, ret=31, audt=1596 sf=1 2009-04-21 14:51:52.676 AO: _AddSamples samples=1152 bytes=4608, used=86017, free=1449983, timecode=2623 needsupmix 0 2009-04-21 14:51:52.676 SetAudiotime set=1240343512.676894, audt=1623 atc=2648 tb=182784 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.676 GetAudiotime now=1240343512.676894, set=1240343512.676894, ret=0, audt=1623 sf=1 2009-04-21 14:51:52.676 AO: Broadcasting free space avail 2009-04-21 14:51:52.692 NVP: A/V Divergence: -1.33618, Rate: -0.00416592, Warpfactor: 0.994605, warpfactor_avg: 1.00001 2009-04-21 14:51:52.708 SetAudiotime set=1240343512.708144, audt=1657 atc=2648 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.708 GetAudiotime now=1240343512.708144, set=1240343512.708144, ret=0, audt=1657 sf=1 2009-04-21 14:51:52.708 AO: Broadcasting free space avail 2009-04-21 14:51:52.708 GetAudiotime now=1240343512.708144, set=1240343512.708144, ret=0, audt=1657 sf=1 2009-04-21 14:51:52.708 GetAudiotime now=1240343512.708144, set=1240343512.708144, ret=0, audt=1657 sf=1 2009-04-21 14:51:52.708 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2646 needsupmix 0 2009-04-21 14:51:52.723 NVP: A/V Divergence: -1.30933, Rate: 0.0268537, Warpfactor: 0.994107, warpfactor_avg: 1.00001 2009-04-21 14:51:52.755 GetAudiotime now=1240343512.755020, set=1240343512.708144, ret=46, audt=1657 sf=1 2009-04-21 14:51:52.755 GetAudiotime now=1240343512.755020, set=1240343512.708144, ret=46, audt=1657 sf=1 2009-04-21 14:51:52.755 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=2693 needsupmix 0 2009-04-21 14:51:52.755 NVP: A/V Divergence: -1.38659, Rate: -0.0772643, Warpfactor: 0.99361, warpfactor_avg: 1.00001 2009-04-21 14:51:52.755 SetAudiotime set=1240343512.755020, audt=1710 atc=2718 tb=179712 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.755 GetAudiotime now=1240343512.755020, set=1240343512.755020, ret=0, audt=1710 sf=1 2009-04-21 14:51:52.755 AO: Broadcasting free space avail 2009-04-21 14:51:52.786 GetAudiotime now=1240343512.786270, set=1240343512.755020, ret=31, audt=1710 sf=1 2009-04-21 14:51:52.786 GetAudiotime now=1240343512.786270, set=1240343512.755020, ret=31, audt=1710 sf=1 2009-04-21 14:51:52.786 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2716 needsupmix 0 2009-04-21 14:51:52.786 SetAudiotime set=1240343512.786270, audt=1741 atc=2741 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.786 GetAudiotime now=1240343512.786270, set=1240343512.786270, ret=0, audt=1741 sf=1 2009-04-21 14:51:52.786 AO: Broadcasting free space avail 2009-04-21 14:51:52.786 NVP: A/V Divergence: -1.4745, Rate: -0.0879039, Warpfactor: 0.993114, warpfactor_avg: 1.00001 2009-04-21 14:51:52.817 GetAudiotime now=1240343512.817521, set=1240343512.786270, ret=31, audt=1741 sf=1 2009-04-21 14:51:52.817 GetAudiotime now=1240343512.817521, set=1240343512.786270, ret=31, audt=1741 sf=1 2009-04-21 14:51:52.817 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2739 needsupmix 0 2009-04-21 14:51:52.817 NVP: A/V Divergence: -1.52545, Rate: -0.0509501, Warpfactor: 0.992617, warpfactor_avg: 1.00001 2009-04-21 14:51:52.817 SetAudiotime set=1240343512.817521, audt=1773 atc=2764 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.817 GetAudiotime now=1240343512.817521, set=1240343512.817521, ret=0, audt=1773 sf=1 2009-04-21 14:51:52.817 AO: Broadcasting free space avail 2009-04-21 14:51:52.848 GetAudiotime now=1240343512.848771, set=1240343512.817521, ret=31, audt=1773 sf=1 2009-04-21 14:51:52.848 GetAudiotime now=1240343512.848771, set=1240343512.817521, ret=31, audt=1773 sf=1 2009-04-21 14:51:52.848 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2762 needsupmix 0 2009-04-21 14:51:52.848 GetAudiotime now=1240343512.848771, set=1240343512.817521, ret=31, audt=1773 sf=1 2009-04-21 14:51:52.848 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=2786 needsupmix 0 2009-04-21 14:51:52.848 NVP: A/V Divergence: -1.55616, Rate: -0.0307199, Warpfactor: 0.992121, warpfactor_avg: 1 2009-04-21 14:51:52.848 SetAudiotime set=1240343512.848771, audt=1803 atc=2811 tb=179712 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.848 GetAudiotime now=1240343512.848771, set=1240343512.848771, ret=0, audt=1803 sf=1 2009-04-21 14:51:52.848 AO: Broadcasting free space avail 2009-04-21 14:51:52.880 GetAudiotime now=1240343512.880021, set=1240343512.848771, ret=31, audt=1803 sf=1 2009-04-21 14:51:52.880 GetAudiotime now=1240343512.880021, set=1240343512.848771, ret=31, audt=1803 sf=1 2009-04-21 14:51:52.880 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2809 needsupmix 0 2009-04-21 14:51:52.880 NVP: A/V Divergence: -1.54924, Rate: 0.00692322, Warpfactor: 0.991625, warpfactor_avg: 1 2009-04-21 14:51:52.895 SetAudiotime set=1240343512.895647, audt=1834 atc=2834 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.895 GetAudiotime now=1240343512.895647, set=1240343512.895647, ret=0, audt=1834 sf=1 2009-04-21 14:51:52.895 AO: Broadcasting free space avail 2009-04-21 14:51:52.911 GetAudiotime now=1240343512.911272, set=1240343512.895647, ret=15, audt=1834 sf=1 2009-04-21 14:51:52.911 GetAudiotime now=1240343512.911272, set=1240343512.895647, ret=15, audt=1834 sf=1 2009-04-21 14:51:52.911 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2832 needsupmix 0 2009-04-21 14:51:52.911 GetAudiotime now=1240343512.911272, set=1240343512.895647, ret=15, audt=1834 sf=1 2009-04-21 14:51:52.911 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=2855 needsupmix 0 2009-04-21 14:51:52.911 NVP: A/V Divergence: -1.40919, Rate: 0.140053, Warpfactor: 0.991129, warpfactor_avg: 1 2009-04-21 14:51:52.926 SetAudiotime set=1240343512.926897, audt=1863 atc=2880 tb=181248 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.926 GetAudiotime now=1240343512.926897, set=1240343512.926897, ret=0, audt=1863 sf=1 2009-04-21 14:51:52.926 AO: Broadcasting free space avail 2009-04-21 14:51:52.942 GetAudiotime now=1240343512.942522, set=1240343512.926897, ret=15, audt=1863 sf=1 2009-04-21 14:51:52.942 NVP: A/V Divergence: -1.27417, Rate: 0.135018, Warpfactor: 0.99089, warpfactor_avg: 1 2009-04-21 14:51:52.958 SetAudiotime set=1240343512.958147, audt=1898 atc=2880 tb=175104 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.958 GetAudiotime now=1240343512.958147, set=1240343512.958147, ret=0, audt=1898 sf=1 2009-04-21 14:51:52.958 AO: Broadcasting free space avail 2009-04-21 14:51:52.973 GetAudiotime now=1240343512.973773, set=1240343512.958147, ret=15, audt=1898 sf=1 2009-04-21 14:51:52.973 GetAudiotime now=1240343512.973773, set=1240343512.958147, ret=15, audt=1898 sf=1 2009-04-21 14:51:52.973 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2902 needsupmix 0 2009-04-21 14:51:52.973 GetAudiotime now=1240343512.973773, set=1240343512.958147, ret=15, audt=1898 sf=1 2009-04-21 14:51:52.973 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=2925 needsupmix 0 2009-04-21 14:51:52.989 NVP: A/V Divergence: -1.18039, Rate: 0.0937781, Warpfactor: 0.99131, warpfactor_avg: 1 2009-04-21 14:51:52.989 SetAudiotime set=1240343512.989398, audt=1950 atc=2950 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:52.989 GetAudiotime now=1240343512.989398, set=1240343512.989398, ret=0, audt=1950 sf=1 2009-04-21 14:51:52.989 AO: Broadcasting free space avail 2009-04-21 14:51:53.020 GetAudiotime now=1240343513.20648, set=1240343512.989398, ret=32, audt=1950 sf=1 2009-04-21 14:51:53.020 GetAudiotime now=1240343513.20648, set=1240343512.989398, ret=32, audt=1950 sf=1 2009-04-21 14:51:53.020 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=2948 needsupmix 0 2009-04-21 14:51:53.020 NVP: A/V Divergence: -1.37979, Rate: -0.199395, Warpfactor: 0.990815, warpfactor_avg: 1 2009-04-21 14:51:53.036 SetAudiotime set=1240343513.36273, audt=1982 atc=2973 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:53.036 GetAudiotime now=1240343513.36273, set=1240343513.36273, ret=0, audt=1982 sf=1 2009-04-21 14:51:53.036 AO: Broadcasting free space avail 2009-04-21 14:51:53.051 GetAudiotime now=1240343513.51899, set=1240343513.36273, ret=15, audt=1982 sf=1 2009-04-21 14:51:53.051 GetAudiotime now=1240343513.51899, set=1240343513.36273, ret=15, audt=1982 sf=1 2009-04-21 14:51:53.051 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=2972 needsupmix 0 2009-04-21 14:51:53.051 NVP: A/V Divergence: -1.39447, Rate: -0.0146856, Warpfactor: 0.990319, warpfactor_avg: 1 2009-04-21 14:51:53.067 SetAudiotime set=1240343513.67524, audt=2015 atc=2997 tb=175104 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:53.067 GetAudiotime now=1240343513.67524, set=1240343513.67524, ret=0, audt=2015 sf=1 2009-04-21 14:51:53.067 AO: Broadcasting free space avail 2009-04-21 14:51:53.083 GetAudiotime now=1240343513.83149, set=1240343513.67524, ret=15, audt=2015 sf=1 2009-04-21 14:51:53.083 GetAudiotime now=1240343513.83149, set=1240343513.67524, ret=15, audt=2015 sf=1 2009-04-21 14:51:53.083 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=2995 needsupmix 0 2009-04-21 14:51:53.083 GetAudiotime now=1240343513.83149, set=1240343513.67524, ret=15, audt=2015 sf=1 2009-04-21 14:51:53.083 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3018 needsupmix 0 2009-04-21 14:51:53.083 NVP: A/V Divergence: -1.39801, Rate: -0.00353653, Warpfactor: 0.989824, warpfactor_avg: 1 2009-04-21 14:51:53.098 SetAudiotime set=1240343513.98774, audt=2043 atc=3043 tb=178176 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:53.098 GetAudiotime now=1240343513.98774, set=1240343513.98774, ret=0, audt=2043 sf=1 2009-04-21 14:51:53.098 AO: Broadcasting free space avail 2009-04-21 14:51:53.114 GetAudiotime now=1240343513.114399, set=1240343513.98774, ret=15, audt=2043 sf=1 2009-04-21 14:51:53.114 GetAudiotime now=1240343513.114399, set=1240343513.98774, ret=15, audt=2043 sf=1 2009-04-21 14:51:53.114 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3041 needsupmix 0 2009-04-21 14:51:53.114 NVP: A/V Divergence: -1.36318, Rate: 0.0348259, Warpfactor: 0.989374, warpfactor_avg: 1 2009-04-21 14:51:53.130 SetAudiotime set=1240343513.130025, audt=2075 atc=3066 tb=176640 sb=92160 eds=4454325 abps=4 sf=1 2009-04-21 14:51:53.130 GetAudiotime now=1240343513.130025, set=1240343513.130025, ret=0, audt=2075 sf=1 2009-04-21 14:51:53.130 AO: Broadcasting free space avail 2009-04-21 14:51:53.145 GetAudiotime now=1240343513.145650, set=1240343513.130025, ret=15, audt=2075 sf=1 2009-04-21 14:51:53.145 GetAudiotime now=1240343513.145650, set=1240343513.130025, ret=15, audt=2075 sf=1 2009-04-21 14:51:53.145 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=3065 needsupmix 0 2009-04-21 14:51:53.145 AO: SetEffDsp: 4437666 2009-04-21 14:51:53.145 NVP: A/V Divergence: -1.32959, Rate: 0.0335971, Warpfactor: 0.989632, warpfactor_avg: 1 2009-04-21 14:51:53.176 SetAudiotime set=1240343513.176900, audt=2104 atc=3090 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.176 GetAudiotime now=1240343513.176900, set=1240343513.176900, ret=0, audt=2104 sf=1 2009-04-21 14:51:53.176 AO: Broadcasting free space avail 2009-04-21 14:51:53.176 GetAudiotime now=1240343513.176900, set=1240343513.176900, ret=0, audt=2104 sf=1 2009-04-21 14:51:53.176 GetAudiotime now=1240343513.176900, set=1240343513.176900, ret=0, audt=2104 sf=1 2009-04-21 14:51:53.176 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=3111 needsupmix 0 2009-04-21 14:51:53.176 NVP: A/V Divergence: -1.15453, Rate: 0.175058, Warpfactor: 0.990127, warpfactor_avg: 0.999999 2009-04-21 14:51:53.208 SetAudiotime set=1240343513.208151, audt=2159 atc=3136 tb=173568 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.208 GetAudiotime now=1240343513.208151, set=1240343513.208151, ret=0, audt=2159 sf=1 2009-04-21 14:51:53.208 AO: Broadcasting free space avail 2009-04-21 14:51:53.208 GetAudiotime now=1240343513.208151, set=1240343513.208151, ret=0, audt=2159 sf=1 2009-04-21 14:51:53.208 GetAudiotime now=1240343513.208151, set=1240343513.208151, ret=0, audt=2159 sf=1 2009-04-21 14:51:53.208 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=3134 needsupmix 0 2009-04-21 14:51:53.208 GetAudiotime now=1240343513.208151, set=1240343513.208151, ret=0, audt=2159 sf=1 2009-04-21 14:51:53.208 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3158 needsupmix 0 2009-04-21 14:51:53.223 NVP: A/V Divergence: -1.18807, Rate: -0.0335371, Warpfactor: 0.990226, warpfactor_avg: 0.999999 2009-04-21 14:51:53.239 SetAudiotime set=1240343513.239401, audt=2188 atc=3183 tb=176640 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.239 GetAudiotime now=1240343513.239401, set=1240343513.239401, ret=0, audt=2188 sf=1 2009-04-21 14:51:53.239 AO: Broadcasting free space avail 2009-04-21 14:51:53.255 GetAudiotime now=1240343513.255026, set=1240343513.239401, ret=15, audt=2188 sf=1 2009-04-21 14:51:53.255 GetAudiotime now=1240343513.255026, set=1240343513.239401, ret=15, audt=2188 sf=1 2009-04-21 14:51:53.255 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=3181 needsupmix 0 2009-04-21 14:51:53.255 NVP: A/V Divergence: -1.28814, Rate: -0.100072, Warpfactor: 0.989731, warpfactor_avg: 0.999998 2009-04-21 14:51:53.270 SetAudiotime set=1240343513.270651, audt=2220 atc=3206 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.270 GetAudiotime now=1240343513.270651, set=1240343513.270651, ret=0, audt=2220 sf=1 2009-04-21 14:51:53.270 AO: Broadcasting free space avail 2009-04-21 14:51:53.286 GetAudiotime now=1240343513.286277, set=1240343513.270651, ret=15, audt=2220 sf=1 2009-04-21 14:51:53.286 GetAudiotime now=1240343513.286277, set=1240343513.270651, ret=15, audt=2220 sf=1 2009-04-21 14:51:53.286 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=3204 needsupmix 0 2009-04-21 14:51:53.286 NVP: A/V Divergence: -1.35572, Rate: -0.0675838, Warpfactor: 0.989236, warpfactor_avg: 0.999997 2009-04-21 14:51:53.317 GetAudiotime now=1240343513.317527, set=1240343513.270651, ret=46, audt=2220 sf=1 2009-04-21 14:51:53.317 GetAudiotime now=1240343513.317527, set=1240343513.270651, ret=46, audt=2220 sf=1 2009-04-21 14:51:53.317 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3227 needsupmix 0 2009-04-21 14:51:53.317 GetAudiotime now=1240343513.317527, set=1240343513.270651, ret=46, audt=2220 sf=1 2009-04-21 14:51:53.317 AO: _AddSamples samples=1152 bytes=4608, used=86017, free=1449983, timecode=3251 needsupmix 0 2009-04-21 14:51:53.317 SetAudiotime set=1240343513.317527, audt=2247 atc=3276 tb=182784 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.317 GetAudiotime now=1240343513.317527, set=1240343513.317527, ret=0, audt=2247 sf=1 2009-04-21 14:51:53.317 AO: Broadcasting free space avail 2009-04-21 14:51:53.317 NVP: A/V Divergence: -1.39142, Rate: -0.035695, Warpfactor: 0.988742, warpfactor_avg: 0.999997 2009-04-21 14:51:53.348 SetAudiotime set=1240343513.348777, audt=2281 atc=3276 tb=176640 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.348 GetAudiotime now=1240343513.348777, set=1240343513.348777, ret=0, audt=2281 sf=1 2009-04-21 14:51:53.348 AO: Broadcasting free space avail 2009-04-21 14:51:53.348 GetAudiotime now=1240343513.348777, set=1240343513.348777, ret=0, audt=2281 sf=1 2009-04-21 14:51:53.348 GetAudiotime now=1240343513.348777, set=1240343513.348777, ret=0, audt=2281 sf=1 2009-04-21 14:51:53.348 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=3274 needsupmix 0 2009-04-21 14:51:53.348 NVP: A/V Divergence: -1.27582, Rate: 0.115597, Warpfactor: 0.989236, warpfactor_avg: 0.999996 2009-04-21 14:51:53.380 GetAudiotime now=1240343513.380028, set=1240343513.348777, ret=31, audt=2281 sf=1 2009-04-21 14:51:53.380 GetAudiotime now=1240343513.380028, set=1240343513.348777, ret=31, audt=2281 sf=1 2009-04-21 14:51:53.380 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=3320 needsupmix 0 2009-04-21 14:51:53.380 NVP: A/V Divergence: -1.17413, Rate: 0.10169, Warpfactor: 0.989731, warpfactor_avg: 0.999996 2009-04-21 14:51:53.380 SetAudiotime set=1240343513.380028, audt=2333 atc=3345 tb=179712 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.380 GetAudiotime now=1240343513.380028, set=1240343513.380028, ret=0, audt=2333 sf=1 2009-04-21 14:51:53.380 AO: Broadcasting free space avail 2009-04-21 14:51:53.411 GetAudiotime now=1240343513.411278, set=1240343513.380028, ret=31, audt=2333 sf=1 2009-04-21 14:51:53.411 GetAudiotime now=1240343513.411278, set=1240343513.380028, ret=31, audt=2333 sf=1 2009-04-21 14:51:53.411 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3344 needsupmix 0 2009-04-21 14:51:53.411 SetAudiotime set=1240343513.411278, audt=2366 atc=3369 tb=178176 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.411 GetAudiotime now=1240343513.411278, set=1240343513.411278, ret=0, audt=2366 sf=1 2009-04-21 14:51:53.411 AO: Broadcasting free space avail 2009-04-21 14:51:53.411 NVP: A/V Divergence: -1.24024, Rate: -0.0661152, Warpfactor: 0.989545, warpfactor_avg: 0.999995 2009-04-21 14:51:53.442 GetAudiotime now=1240343513.442529, set=1240343513.411278, ret=31, audt=2366 sf=1 2009-04-21 14:51:53.442 GetAudiotime now=1240343513.442529, set=1240343513.411278, ret=31, audt=2366 sf=1 2009-04-21 14:51:53.442 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3367 needsupmix 0 2009-04-21 14:51:53.442 GetAudiotime now=1240343513.442529, set=1240343513.411278, ret=31, audt=2366 sf=1 2009-04-21 14:51:53.442 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=3390 needsupmix 0 2009-04-21 14:51:53.458 NVP: A/V Divergence: -1.28232, Rate: -0.0420788, Warpfactor: 0.9894, warpfactor_avg: 0.999994 2009-04-21 14:51:53.458 SetAudiotime set=1240343513.458154, audt=2394 atc=3415 tb=181248 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.458 GetAudiotime now=1240343513.458154, set=1240343513.458154, ret=0, audt=2394 sf=1 2009-04-21 14:51:53.458 AO: Broadcasting free space avail 2009-04-21 14:51:53.489 GetAudiotime now=1240343513.489404, set=1240343513.458154, ret=31, audt=2394 sf=1 2009-04-21 14:51:53.489 GetAudiotime now=1240343513.489404, set=1240343513.458154, ret=31, audt=2394 sf=1 2009-04-21 14:51:53.489 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=3413 needsupmix 0 2009-04-21 14:51:53.489 SetAudiotime set=1240343513.489404, audt=2426 atc=3438 tb=179712 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.489 GetAudiotime now=1240343513.489404, set=1240343513.489404, ret=0, audt=2426 sf=1 2009-04-21 14:51:53.489 AO: Broadcasting free space avail 2009-04-21 14:51:53.489 NVP: A/V Divergence: -1.27642, Rate: 0.00590421, Warpfactor: 0.98983, warpfactor_avg: 0.999994 2009-04-21 14:51:53.520 GetAudiotime now=1240343513.520655, set=1240343513.489404, ret=31, audt=2426 sf=1 2009-04-21 14:51:53.520 GetAudiotime now=1240343513.520655, set=1240343513.489404, ret=31, audt=2426 sf=1 2009-04-21 14:51:53.520 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3437 needsupmix 0 2009-04-21 14:51:53.520 GetAudiotime now=1240343513.520655, set=1240343513.489404, ret=31, audt=2426 sf=1 2009-04-21 14:51:53.520 AO: _AddSamples samples=1152 bytes=4608, used=86017, free=1449983, timecode=3460 needsupmix 0 2009-04-21 14:51:53.520 NVP: A/V Divergence: -1.257, Rate: 0.019421, Warpfactor: 0.990093, warpfactor_avg: 0.999993 2009-04-21 14:51:53.520 SetAudiotime set=1240343513.520655, audt=2456 atc=3485 tb=182784 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.520 GetAudiotime now=1240343513.520655, set=1240343513.520655, ret=0, audt=2456 sf=1 2009-04-21 14:51:53.520 AO: Broadcasting free space avail 2009-04-21 14:51:53.551 GetAudiotime now=1240343513.551905, set=1240343513.520655, ret=31, audt=2456 sf=1 2009-04-21 14:51:53.551 GetAudiotime now=1240343513.551905, set=1240343513.520655, ret=31, audt=2456 sf=1 2009-04-21 14:51:53.551 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=3483 needsupmix 0 2009-04-21 14:51:53.551 NVP: A/V Divergence: -1.21995, Rate: 0.0370437, Warpfactor: 0.99053, warpfactor_avg: 0.999993 2009-04-21 14:51:53.551 SetAudiotime set=1240343513.551905, audt=2487 atc=3508 tb=181248 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.551 GetAudiotime now=1240343513.551905, set=1240343513.551905, ret=0, audt=2487 sf=1 2009-04-21 14:51:53.551 AO: Broadcasting free space avail 2009-04-21 14:51:53.583 GetAudiotime now=1240343513.583155, set=1240343513.551905, ret=31, audt=2487 sf=1 2009-04-21 14:51:53.583 NVP: A/V Divergence: -1.17719, Rate: 0.0427681, Warpfactor: 0.990918, warpfactor_avg: 0.999992 2009-04-21 14:51:53.598 SetAudiotime set=1240343513.598781, audt=2522 atc=3508 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.598 GetAudiotime now=1240343513.598781, set=1240343513.598781, ret=0, audt=2522 sf=1 2009-04-21 14:51:53.598 AO: Broadcasting free space avail 2009-04-21 14:51:53.614 GetAudiotime now=1240343513.614406, set=1240343513.598781, ret=15, audt=2522 sf=1 2009-04-21 14:51:53.614 GetAudiotime now=1240343513.614406, set=1240343513.598781, ret=15, audt=2522 sf=1 2009-04-21 14:51:53.614 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=3530 needsupmix 0 2009-04-21 14:51:53.614 GetAudiotime now=1240343513.614406, set=1240343513.598781, ret=15, audt=2522 sf=1 2009-04-21 14:51:53.614 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3553 needsupmix 0 2009-04-21 14:51:53.614 NVP: A/V Divergence: -1.03273, Rate: 0.144458, Warpfactor: 0.991413, warpfactor_avg: 0.999992 2009-04-21 14:51:53.630 SetAudiotime set=1240343513.630031, audt=2575 atc=3578 tb=178176 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.630 GetAudiotime now=1240343513.630031, set=1240343513.630031, ret=0, audt=2575 sf=1 2009-04-21 14:51:53.630 AO: Broadcasting free space avail 2009-04-21 14:51:53.645 GetAudiotime now=1240343513.645656, set=1240343513.630031, ret=15, audt=2575 sf=1 2009-04-21 14:51:53.645 GetAudiotime now=1240343513.645656, set=1240343513.630031, ret=15, audt=2575 sf=1 2009-04-21 14:51:53.645 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3576 needsupmix 0 2009-04-21 14:51:53.645 NVP: A/V Divergence: -1.07424, Rate: -0.0415093, Warpfactor: 0.991066, warpfactor_avg: 0.999991 2009-04-21 14:51:53.661 SetAudiotime set=1240343513.661281, audt=2606 atc=3601 tb=176640 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.661 GetAudiotime now=1240343513.661281, set=1240343513.661281, ret=0, audt=2606 sf=1 2009-04-21 14:51:53.661 AO: Broadcasting free space avail 2009-04-21 14:51:53.676 GetAudiotime now=1240343513.676907, set=1240343513.661281, ret=15, audt=2606 sf=1 2009-04-21 14:51:53.676 GetAudiotime now=1240343513.676907, set=1240343513.661281, ret=15, audt=2606 sf=1 2009-04-21 14:51:53.676 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=3599 needsupmix 0 2009-04-21 14:51:53.676 GetAudiotime now=1240343513.676907, set=1240343513.661281, ret=15, audt=2606 sf=1 2009-04-21 14:51:53.676 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=3622 needsupmix 0 2009-04-21 14:51:53.676 NVP: A/V Divergence: -1.09039, Rate: -0.0161542, Warpfactor: 0.991139, warpfactor_avg: 0.999991 2009-04-21 14:51:53.692 SetAudiotime set=1240343513.692532, audt=2635 atc=3647 tb=179712 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.692 GetAudiotime now=1240343513.692532, set=1240343513.692532, ret=0, audt=2635 sf=1 2009-04-21 14:51:53.692 AO: Broadcasting free space avail 2009-04-21 14:51:53.708 GetAudiotime now=1240343513.708157, set=1240343513.692532, ret=15, audt=2635 sf=1 2009-04-21 14:51:53.708 GetAudiotime now=1240343513.708157, set=1240343513.692532, ret=15, audt=2635 sf=1 2009-04-21 14:51:53.708 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3646 needsupmix 0 2009-04-21 14:51:53.723 NVP: A/V Divergence: -1.06504, Rate: 0.0253552, Warpfactor: 0.991635, warpfactor_avg: 0.99999 2009-04-21 14:51:53.723 SetAudiotime set=1240343513.723782, audt=2668 atc=3671 tb=178176 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.723 GetAudiotime now=1240343513.723782, set=1240343513.723782, ret=0, audt=2668 sf=1 2009-04-21 14:51:53.723 AO: Broadcasting free space avail 2009-04-21 14:51:53.755 GetAudiotime now=1240343513.755033, set=1240343513.723782, ret=31, audt=2668 sf=1 2009-04-21 14:51:53.755 GetAudiotime now=1240343513.755033, set=1240343513.723782, ret=31, audt=2668 sf=1 2009-04-21 14:51:53.755 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3669 needsupmix 0 2009-04-21 14:51:53.755 GetAudiotime now=1240343513.755033, set=1240343513.723782, ret=31, audt=2668 sf=1 2009-04-21 14:51:53.755 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=3692 needsupmix 0 2009-04-21 14:51:53.755 NVP: A/V Divergence: -1.16592, Rate: -0.100881, Warpfactor: 0.991139, warpfactor_avg: 0.99999 2009-04-21 14:51:53.770 SetAudiotime set=1240343513.770658, audt=2696 atc=3717 tb=181248 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.770 GetAudiotime now=1240343513.770658, set=1240343513.770658, ret=0, audt=2696 sf=1 2009-04-21 14:51:53.770 AO: Broadcasting free space avail 2009-04-21 14:51:53.786 GetAudiotime now=1240343513.786283, set=1240343513.770658, ret=15, audt=2696 sf=1 2009-04-21 14:51:53.786 NVP: A/V Divergence: -1.07672, Rate: 0.0891926, Warpfactor: 0.991634, warpfactor_avg: 0.99999 2009-04-21 14:51:53.801 SetAudiotime set=1240343513.801908, audt=2731 atc=3717 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.801 GetAudiotime now=1240343513.801908, set=1240343513.801908, ret=0, audt=2731 sf=1 2009-04-21 14:51:53.801 AO: Broadcasting free space avail 2009-04-21 14:51:53.817 GetAudiotime now=1240343513.817533, set=1240343513.801908, ret=15, audt=2731 sf=1 2009-04-21 14:51:53.817 GetAudiotime now=1240343513.817533, set=1240343513.801908, ret=15, audt=2731 sf=1 2009-04-21 14:51:53.817 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=3739 needsupmix 0 2009-04-21 14:51:53.817 NVP: A/V Divergence: -1.02482, Rate: 0.0519091, Warpfactor: 0.99213, warpfactor_avg: 0.999989 2009-04-21 14:51:53.833 SetAudiotime set=1240343513.833159, audt=2787 atc=3764 tb=173568 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.833 GetAudiotime now=1240343513.833159, set=1240343513.833159, ret=0, audt=2787 sf=1 2009-04-21 14:51:53.833 AO: Broadcasting free space avail 2009-04-21 14:51:53.848 GetAudiotime now=1240343513.848784, set=1240343513.833159, ret=15, audt=2787 sf=1 2009-04-21 14:51:53.848 GetAudiotime now=1240343513.848784, set=1240343513.833159, ret=15, audt=2787 sf=1 2009-04-21 14:51:53.848 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=3762 needsupmix 0 2009-04-21 14:51:53.848 GetAudiotime now=1240343513.848784, set=1240343513.833159, ret=15, audt=2787 sf=1 2009-04-21 14:51:53.848 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3785 needsupmix 0 2009-04-21 14:51:53.848 NVP: A/V Divergence: -1.15821, Rate: -0.133399, Warpfactor: 0.991634, warpfactor_avg: 0.999989 2009-04-21 14:51:53.864 SetAudiotime set=1240343513.864409, audt=2815 atc=3810 tb=176640 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.864 GetAudiotime now=1240343513.864409, set=1240343513.864409, ret=0, audt=2815 sf=1 2009-04-21 14:51:53.864 AO: Broadcasting free space avail 2009-04-21 14:51:53.880 GetAudiotime now=1240343513.880034, set=1240343513.864409, ret=15, audt=2815 sf=1 2009-04-21 14:51:53.880 GetAudiotime now=1240343513.880034, set=1240343513.864409, ret=15, audt=2815 sf=1 2009-04-21 14:51:53.880 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=3808 needsupmix 0 2009-04-21 14:51:53.880 NVP: A/V Divergence: -1.2133, Rate: -0.055086, Warpfactor: 0.991138, warpfactor_avg: 0.999988 2009-04-21 14:51:53.911 SetAudiotime set=1240343513.911285, audt=2847 atc=3833 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.911 GetAudiotime now=1240343513.911285, set=1240343513.911285, ret=0, audt=2847 sf=1 2009-04-21 14:51:53.911 AO: Broadcasting free space avail 2009-04-21 14:51:53.911 GetAudiotime now=1240343513.911285, set=1240343513.911285, ret=0, audt=2847 sf=1 2009-04-21 14:51:53.911 GetAudiotime now=1240343513.911285, set=1240343513.911285, ret=0, audt=2847 sf=1 2009-04-21 14:51:53.911 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=3832 needsupmix 0 2009-04-21 14:51:53.911 GetAudiotime now=1240343513.911285, set=1240343513.911285, ret=0, audt=2847 sf=1 2009-04-21 14:51:53.911 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=3855 needsupmix 0 2009-04-21 14:51:53.911 NVP: A/V Divergence: -1.13475, Rate: 0.078553, Warpfactor: 0.991539, warpfactor_avg: 0.999988 2009-04-21 14:51:53.942 SetAudiotime set=1240343513.942535, audt=2877 atc=3880 tb=178176 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.942 GetAudiotime now=1240343513.942535, set=1240343513.942535, ret=0, audt=2877 sf=1 2009-04-21 14:51:53.942 AO: Broadcasting free space avail 2009-04-21 14:51:53.942 GetAudiotime now=1240343513.942535, set=1240343513.942535, ret=0, audt=2877 sf=1 2009-04-21 14:51:53.942 GetAudiotime now=1240343513.942535, set=1240343513.942535, ret=0, audt=2877 sf=1 2009-04-21 14:51:53.942 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3878 needsupmix 0 2009-04-21 14:51:53.958 NVP: A/V Divergence: -1.05335, Rate: 0.0814002, Warpfactor: 0.992034, warpfactor_avg: 0.999987 2009-04-21 14:51:53.973 SetAudiotime set=1240343513.973785, audt=2908 atc=3903 tb=176640 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:53.973 GetAudiotime now=1240343513.973785, set=1240343513.973785, ret=0, audt=2908 sf=1 2009-04-21 14:51:53.973 AO: Broadcasting free space avail 2009-04-21 14:51:53.989 GetAudiotime now=1240343513.989411, set=1240343513.973785, ret=15, audt=2908 sf=1 2009-04-21 14:51:53.989 GetAudiotime now=1240343513.989411, set=1240343513.973785, ret=15, audt=2908 sf=1 2009-04-21 14:51:53.989 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=3901 needsupmix 0 2009-04-21 14:51:53.989 NVP: A/V Divergence: -1.08221, Rate: -0.0288617, Warpfactor: 0.991538, warpfactor_avg: 0.999987 2009-04-21 14:51:54.005 SetAudiotime set=1240343514.5036, audt=2940 atc=3926 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:54.005 GetAudiotime now=1240343514.5036, set=1240343514.5036, ret=0, audt=2940 sf=1 2009-04-21 14:51:54.005 AO: Broadcasting free space avail 2009-04-21 14:51:54.020 GetAudiotime now=1240343514.20661, set=1240343514.5036, ret=15, audt=2940 sf=1 2009-04-21 14:51:54.020 GetAudiotime now=1240343514.20661, set=1240343514.5036, ret=15, audt=2940 sf=1 2009-04-21 14:51:54.020 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=3948 needsupmix 0 2009-04-21 14:51:54.020 NVP: A/V Divergence: -1.09636, Rate: -0.0141461, Warpfactor: 0.991103, warpfactor_avg: 0.999986 2009-04-21 14:51:54.036 SetAudiotime set=1240343514.36286, audt=2996 atc=3973 tb=173568 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:54.036 GetAudiotime now=1240343514.36286, set=1240343514.36286, ret=0, audt=2996 sf=1 2009-04-21 14:51:54.036 AO: Broadcasting free space avail 2009-04-21 14:51:54.051 GetAudiotime now=1240343514.51911, set=1240343514.36286, ret=15, audt=2996 sf=1 2009-04-21 14:51:54.051 GetAudiotime now=1240343514.51911, set=1240343514.36286, ret=15, audt=2996 sf=1 2009-04-21 14:51:54.051 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=3971 needsupmix 0 2009-04-21 14:51:54.051 GetAudiotime now=1240343514.51911, set=1240343514.36286, ret=15, audt=2996 sf=1 2009-04-21 14:51:54.051 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=3994 needsupmix 0 2009-04-21 14:51:54.051 NVP: A/V Divergence: -1.2793, Rate: -0.182941, Warpfactor: 0.990607, warpfactor_avg: 0.999986 2009-04-21 14:51:54.083 SetAudiotime set=1240343514.83162, audt=3024 atc=4019 tb=176640 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:54.083 GetAudiotime now=1240343514.83162, set=1240343514.83162, ret=0, audt=3024 sf=1 2009-04-21 14:51:54.083 AO: Broadcasting free space avail 2009-04-21 14:51:54.083 GetAudiotime now=1240343514.83162, set=1240343514.83162, ret=0, audt=3024 sf=1 2009-04-21 14:51:54.083 GetAudiotime now=1240343514.83162, set=1240343514.83162, ret=0, audt=3024 sf=1 2009-04-21 14:51:54.083 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4018 needsupmix 0 2009-04-21 14:51:54.083 NVP: A/V Divergence: -1.25916, Rate: 0.0201403, Warpfactor: 0.990112, warpfactor_avg: 0.999985 2009-04-21 14:51:54.114 SetAudiotime set=1240343514.114412, audt=3057 atc=4043 tb=175104 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:54.114 GetAudiotime now=1240343514.114412, set=1240343514.114412, ret=0, audt=3057 sf=1 2009-04-21 14:51:54.114 AO: Broadcasting free space avail 2009-04-21 14:51:54.114 GetAudiotime now=1240343514.114412, set=1240343514.114412, ret=0, audt=3057 sf=1 2009-04-21 14:51:54.114 GetAudiotime now=1240343514.114412, set=1240343514.114412, ret=0, audt=3057 sf=1 2009-04-21 14:51:54.114 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4041 needsupmix 0 2009-04-21 14:51:54.114 NVP: A/V Divergence: -1.24405, Rate: 0.0151052, Warpfactor: 0.990154, warpfactor_avg: 0.999985 2009-04-21 14:51:54.145 SetAudiotime set=1240343514.145663, audt=3089 atc=4066 tb=173568 sb=92160 eds=4437666 abps=4 sf=1 2009-04-21 14:51:54.145 GetAudiotime now=1240343514.145663, set=1240343514.145663, ret=0, audt=3089 sf=1 2009-04-21 14:51:54.145 AO: Broadcasting free space avail 2009-04-21 14:51:54.145 GetAudiotime now=1240343514.145663, set=1240343514.145663, ret=0, audt=3089 sf=1 2009-04-21 14:51:54.145 GetAudiotime now=1240343514.145663, set=1240343514.145663, ret=0, audt=3089 sf=1 2009-04-21 14:51:54.145 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=4064 needsupmix 0 2009-04-21 14:51:54.145 GetAudiotime now=1240343514.145663, set=1240343514.145663, ret=0, audt=3089 sf=1 2009-04-21 14:51:54.145 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=4087 needsupmix 0 2009-04-21 14:51:54.145 AO: SetEffDsp: 4430285 2009-04-21 14:51:54.145 NVP: A/V Divergence: -1.22523, Rate: 0.0188216, Warpfactor: 0.990334, warpfactor_avg: 0.999984 2009-04-21 14:51:54.176 GetAudiotime now=1240343514.176913, set=1240343514.145663, ret=31, audt=3089 sf=1 2009-04-21 14:51:54.176 GetAudiotime now=1240343514.176913, set=1240343514.145663, ret=31, audt=3089 sf=1 2009-04-21 14:51:54.176 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=4111 needsupmix 0 2009-04-21 14:51:54.176 SetAudiotime set=1240343514.176913, audt=3115 atc=4137 tb=181248 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.176 GetAudiotime now=1240343514.176913, set=1240343514.176913, ret=0, audt=3115 sf=1 2009-04-21 14:51:54.176 AO: Broadcasting free space avail 2009-04-21 14:51:54.192 NVP: A/V Divergence: -1.18864, Rate: 0.0365941, Warpfactor: 0.990768, warpfactor_avg: 0.999984 2009-04-21 14:51:54.208 SetAudiotime set=1240343514.208163, audt=3149 atc=4137 tb=175104 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.208 GetAudiotime now=1240343514.208163, set=1240343514.208163, ret=0, audt=3149 sf=1 2009-04-21 14:51:54.208 AO: Broadcasting free space avail 2009-04-21 14:51:54.208 GetAudiotime now=1240343514.208163, set=1240343514.208163, ret=0, audt=3149 sf=1 2009-04-21 14:51:54.208 GetAudiotime now=1240343514.208163, set=1240343514.208163, ret=0, audt=3149 sf=1 2009-04-21 14:51:54.208 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4157 needsupmix 0 2009-04-21 14:51:54.223 NVP: A/V Divergence: -1.13124, Rate: 0.0573938, Warpfactor: 0.991263, warpfactor_avg: 0.999983 2009-04-21 14:51:54.255 GetAudiotime now=1240343514.255039, set=1240343514.208163, ret=46, audt=3149 sf=1 2009-04-21 14:51:54.255 GetAudiotime now=1240343514.255039, set=1240343514.208163, ret=46, audt=3149 sf=1 2009-04-21 14:51:54.255 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=4180 needsupmix 0 2009-04-21 14:51:54.255 NVP: A/V Divergence: -1.17809, Rate: -0.0468441, Warpfactor: 0.990768, warpfactor_avg: 0.999983 2009-04-21 14:51:54.255 SetAudiotime set=1240343514.255039, audt=3201 atc=4206 tb=178176 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.255 GetAudiotime now=1240343514.255039, set=1240343514.255039, ret=0, audt=3201 sf=1 2009-04-21 14:51:54.255 AO: Broadcasting free space avail 2009-04-21 14:51:54.286 SetAudiotime set=1240343514.286289, audt=3236 atc=4206 tb=172032 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.286 GetAudiotime now=1240343514.286289, set=1240343514.286289, ret=0, audt=3236 sf=1 2009-04-21 14:51:54.286 AO: Broadcasting free space avail 2009-04-21 14:51:54.286 GetAudiotime now=1240343514.286289, set=1240343514.286289, ret=0, audt=3236 sf=1 2009-04-21 14:51:54.286 GetAudiotime now=1240343514.286289, set=1240343514.286289, ret=0, audt=3236 sf=1 2009-04-21 14:51:54.286 AO: _AddSamples samples=1152 bytes=4608, used=73729, free=1462271, timecode=4204 needsupmix 0 2009-04-21 14:51:54.286 GetAudiotime now=1240343514.286289, set=1240343514.286289, ret=0, audt=3236 sf=1 2009-04-21 14:51:54.286 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4227 needsupmix 0 2009-04-21 14:51:54.286 NVP: A/V Divergence: -1.27318, Rate: -0.0950968, Warpfactor: 0.990272, warpfactor_avg: 0.999982 2009-04-21 14:51:54.317 GetAudiotime now=1240343514.317540, set=1240343514.286289, ret=31, audt=3236 sf=1 2009-04-21 14:51:54.317 GetAudiotime now=1240343514.317540, set=1240343514.286289, ret=31, audt=3236 sf=1 2009-04-21 14:51:54.317 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=4250 needsupmix 0 2009-04-21 14:51:54.317 NVP: A/V Divergence: -1.3295, Rate: -0.0563148, Warpfactor: 0.989777, warpfactor_avg: 0.999981 2009-04-21 14:51:54.317 SetAudiotime set=1240343514.317540, audt=3262 atc=4276 tb=179712 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.317 GetAudiotime now=1240343514.317540, set=1240343514.317540, ret=0, audt=3262 sf=1 2009-04-21 14:51:54.317 AO: Broadcasting free space avail 2009-04-21 14:51:54.348 GetAudiotime now=1240343514.348790, set=1240343514.317540, ret=31, audt=3262 sf=1 2009-04-21 14:51:54.348 GetAudiotime now=1240343514.348790, set=1240343514.317540, ret=31, audt=3262 sf=1 2009-04-21 14:51:54.348 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=4273 needsupmix 0 2009-04-21 14:51:54.348 NVP: A/V Divergence: -1.31181, Rate: 0.0176827, Warpfactor: 0.989629, warpfactor_avg: 0.999981 2009-04-21 14:51:54.348 SetAudiotime set=1240343514.348790, audt=3294 atc=4299 tb=178176 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.348 GetAudiotime now=1240343514.348790, set=1240343514.348790, ret=0, audt=3294 sf=1 2009-04-21 14:51:54.348 AO: Broadcasting free space avail 2009-04-21 14:51:54.380 GetAudiotime now=1240343514.380041, set=1240343514.348790, ret=31, audt=3294 sf=1 2009-04-21 14:51:54.380 GetAudiotime now=1240343514.380041, set=1240343514.348790, ret=31, audt=3294 sf=1 2009-04-21 14:51:54.380 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=4297 needsupmix 0 2009-04-21 14:51:54.380 GetAudiotime now=1240343514.380041, set=1240343514.348790, ret=31, audt=3294 sf=1 2009-04-21 14:51:54.380 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=4320 needsupmix 0 2009-04-21 14:51:54.380 NVP: A/V Divergence: -1.29104, Rate: 0.0207696, Warpfactor: 0.989819, warpfactor_avg: 0.99998 2009-04-21 14:51:54.395 SetAudiotime set=1240343514.395666, audt=3324 atc=4346 tb=181248 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.395 GetAudiotime now=1240343514.395666, set=1240343514.395666, ret=0, audt=3324 sf=1 2009-04-21 14:51:54.395 AO: Broadcasting free space avail 2009-04-21 14:51:54.411 GetAudiotime now=1240343514.411291, set=1240343514.395666, ret=15, audt=3324 sf=1 2009-04-21 14:51:54.411 NVP: A/V Divergence: -1.1331, Rate: 0.157945, Warpfactor: 0.990314, warpfactor_avg: 0.99998 2009-04-21 14:51:54.426 SetAudiotime set=1240343514.426916, audt=3358 atc=4346 tb=175104 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.426 GetAudiotime now=1240343514.426916, set=1240343514.426916, ret=0, audt=3358 sf=1 2009-04-21 14:51:54.426 AO: Broadcasting free space avail 2009-04-21 14:51:54.442 GetAudiotime now=1240343514.442541, set=1240343514.426916, ret=15, audt=3358 sf=1 2009-04-21 14:51:54.442 GetAudiotime now=1240343514.442541, set=1240343514.426916, ret=15, audt=3358 sf=1 2009-04-21 14:51:54.442 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4366 needsupmix 0 2009-04-21 14:51:54.442 GetAudiotime now=1240343514.442541, set=1240343514.426916, ret=15, audt=3358 sf=1 2009-04-21 14:51:54.442 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=4390 needsupmix 0 2009-04-21 14:51:54.458 NVP: A/V Divergence: -1.01466, Rate: 0.118444, Warpfactor: 0.990809, warpfactor_avg: 0.999979 2009-04-21 14:51:54.458 SetAudiotime set=1240343514.458167, audt=3411 atc=4416 tb=178176 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.458 GetAudiotime now=1240343514.458167, set=1240343514.458167, ret=0, audt=3411 sf=1 2009-04-21 14:51:54.458 AO: Broadcasting free space avail 2009-04-21 14:51:54.489 GetAudiotime now=1240343514.489417, set=1240343514.458167, ret=31, audt=3411 sf=1 2009-04-21 14:51:54.489 GetAudiotime now=1240343514.489417, set=1240343514.458167, ret=31, audt=3411 sf=1 2009-04-21 14:51:54.489 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=4413 needsupmix 0 2009-04-21 14:51:54.489 NVP: A/V Divergence: -1.19556, Rate: -0.180903, Warpfactor: 0.990313, warpfactor_avg: 0.999979 2009-04-21 14:51:54.489 SetAudiotime set=1240343514.489417, audt=3443 atc=4439 tb=176640 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.489 GetAudiotime now=1240343514.489417, set=1240343514.489417, ret=0, audt=3443 sf=1 2009-04-21 14:51:54.489 AO: Broadcasting free space avail 'video_output' mean = '39531.75', std. dev. = '14470.63', fps = '25.30' 2009-04-21 14:51:54.520 A/V avsync_delay: -58, avsync_avg: -39, warpfactor: 0.990313, warpfactor_avg: 0.999979 2009-04-21 14:51:54.520 GetAudiotime now=1240343514.520667, set=1240343514.489417, ret=31, audt=3443 sf=1 2009-04-21 14:51:54.520 GetAudiotime now=1240343514.520667, set=1240343514.489417, ret=31, audt=3443 sf=1 2009-04-21 14:51:54.520 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4436 needsupmix 0 2009-04-21 14:51:54.520 GetAudiotime now=1240343514.520667, set=1240343514.489417, ret=31, audt=3443 sf=1 2009-04-21 14:51:54.520 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=4459 needsupmix 0 2009-04-21 14:51:54.520 NVP: A/V Divergence: -1.31625, Rate: -0.120692, Warpfactor: 0.989818, warpfactor_avg: 0.999978 2009-04-21 14:51:54.536 SetAudiotime set=1240343514.536293, audt=3471 atc=4485 tb=179712 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.536 GetAudiotime now=1240343514.536293, set=1240343514.536293, ret=0, audt=3471 sf=1 2009-04-21 14:51:54.536 AO: Broadcasting free space avail 2009-04-21 14:51:54.551 GetAudiotime now=1240343514.551918, set=1240343514.536293, ret=15, audt=3471 sf=1 2009-04-21 14:51:54.551 GetAudiotime now=1240343514.551918, set=1240343514.536293, ret=15, audt=3471 sf=1 2009-04-21 14:51:54.551 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=4483 needsupmix 0 2009-04-21 14:51:54.551 NVP: A/V Divergence: -1.24942, Rate: 0.0668345, Warpfactor: 0.990313, warpfactor_avg: 0.999978 2009-04-21 14:51:54.567 SetAudiotime set=1240343514.567543, audt=3504 atc=4509 tb=178176 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.567 GetAudiotime now=1240343514.567543, set=1240343514.567543, ret=0, audt=3504 sf=1 2009-04-21 14:51:54.567 AO: Broadcasting free space avail 2009-04-21 14:51:54.583 GetAudiotime now=1240343514.583168, set=1240343514.567543, ret=15, audt=3504 sf=1 2009-04-21 14:51:54.583 GetAudiotime now=1240343514.583168, set=1240343514.567543, ret=15, audt=3504 sf=1 2009-04-21 14:51:54.583 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=4506 needsupmix 0 2009-04-21 14:51:54.583 NVP: A/V Divergence: -1.1993, Rate: 0.0501109, Warpfactor: 0.990784, warpfactor_avg: 0.999977 2009-04-21 14:51:54.598 SetAudiotime set=1240343514.598793, audt=3536 atc=4532 tb=176640 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.598 GetAudiotime now=1240343514.598793, set=1240343514.598793, ret=0, audt=3536 sf=1 2009-04-21 14:51:54.598 AO: Broadcasting free space avail 2009-04-21 14:51:54.614 GetAudiotime now=1240343514.614419, set=1240343514.598793, ret=15, audt=3536 sf=1 2009-04-21 14:51:54.614 GetAudiotime now=1240343514.614419, set=1240343514.598793, ret=15, audt=3536 sf=1 2009-04-21 14:51:54.614 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4529 needsupmix 0 2009-04-21 14:51:54.614 NVP: A/V Divergence: -1.14674, Rate: 0.0525685, Warpfactor: 0.991224, warpfactor_avg: 0.999977 2009-04-21 14:51:54.630 SetAudiotime set=1240343514.630044, audt=3567 atc=4555 tb=175104 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.630 GetAudiotime now=1240343514.630044, set=1240343514.630044, ret=0, audt=3567 sf=1 2009-04-21 14:51:54.630 AO: Broadcasting free space avail 2009-04-21 14:51:54.645 GetAudiotime now=1240343514.645669, set=1240343514.630044, ret=15, audt=3567 sf=1 2009-04-21 14:51:54.645 GetAudiotime now=1240343514.645669, set=1240343514.630044, ret=15, audt=3567 sf=1 2009-04-21 14:51:54.645 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4576 needsupmix 0 2009-04-21 14:51:54.645 NVP: A/V Divergence: -1.09231, Rate: 0.0544267, Warpfactor: 0.991674, warpfactor_avg: 0.999976 2009-04-21 14:51:54.661 SetAudiotime set=1240343514.661294, audt=3623 atc=4602 tb=173568 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.661 GetAudiotime now=1240343514.661294, set=1240343514.661294, ret=0, audt=3623 sf=1 2009-04-21 14:51:54.661 AO: Broadcasting free space avail 2009-04-21 14:51:54.676 GetAudiotime now=1240343514.676919, set=1240343514.661294, ret=15, audt=3623 sf=1 2009-04-21 14:51:54.676 GetAudiotime now=1240343514.676919, set=1240343514.661294, ret=15, audt=3623 sf=1 2009-04-21 14:51:54.676 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=4599 needsupmix 0 2009-04-21 14:51:54.676 GetAudiotime now=1240343514.676919, set=1240343514.661294, ret=15, audt=3623 sf=1 2009-04-21 14:51:54.676 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=4622 needsupmix 0 2009-04-21 14:51:54.692 NVP: A/V Divergence: -1.22382, Rate: -0.131511, Warpfactor: 0.991178, warpfactor_avg: 0.999976 2009-04-21 14:51:54.708 SetAudiotime set=1240343514.708170, audt=3652 atc=4648 tb=176640 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.708 GetAudiotime now=1240343514.708170, set=1240343514.708170, ret=0, audt=3652 sf=1 2009-04-21 14:51:54.708 AO: Broadcasting free space avail 2009-04-21 14:51:54.723 GetAudiotime now=1240343514.723795, set=1240343514.708170, ret=15, audt=3652 sf=1 2009-04-21 14:51:54.723 GetAudiotime now=1240343514.723795, set=1240343514.708170, ret=15, audt=3652 sf=1 2009-04-21 14:51:54.723 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4645 needsupmix 0 2009-04-21 14:51:54.723 NVP: A/V Divergence: -1.28499, Rate: -0.0611701, Warpfactor: 0.990682, warpfactor_avg: 0.999975 2009-04-21 14:51:54.739 SetAudiotime set=1240343514.739420, audt=3683 atc=4671 tb=175104 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.739 GetAudiotime now=1240343514.739420, set=1240343514.739420, ret=0, audt=3683 sf=1 2009-04-21 14:51:54.739 AO: Broadcasting free space avail 2009-04-21 14:51:54.755 GetAudiotime now=1240343514.755045, set=1240343514.739420, ret=15, audt=3683 sf=1 2009-04-21 14:51:54.755 GetAudiotime now=1240343514.755045, set=1240343514.739420, ret=15, audt=3683 sf=1 2009-04-21 14:51:54.755 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4669 needsupmix 0 2009-04-21 14:51:54.755 GetAudiotime now=1240343514.755045, set=1240343514.739420, ret=15, audt=3683 sf=1 2009-04-21 14:51:54.755 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=4692 needsupmix 0 2009-04-21 14:51:54.755 NVP: A/V Divergence: -1.31589, Rate: -0.0308997, Warpfactor: 0.990187, warpfactor_avg: 0.999975 2009-04-21 14:51:54.770 SetAudiotime set=1240343514.770671, audt=3713 atc=4718 tb=178176 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.770 GetAudiotime now=1240343514.770671, set=1240343514.770671, ret=0, audt=3713 sf=1 2009-04-21 14:51:54.770 AO: Broadcasting free space avail 2009-04-21 14:51:54.786 GetAudiotime now=1240343514.786296, set=1240343514.770671, ret=15, audt=3713 sf=1 2009-04-21 14:51:54.786 GetAudiotime now=1240343514.786296, set=1240343514.770671, ret=15, audt=3713 sf=1 2009-04-21 14:51:54.786 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=4715 needsupmix 0 2009-04-21 14:51:54.786 NVP: A/V Divergence: -1.30909, Rate: 0.00680333, Warpfactor: 0.989692, warpfactor_avg: 0.999974 2009-04-21 14:51:54.801 SetAudiotime set=1240343514.801921, audt=3745 atc=4741 tb=176640 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.801 GetAudiotime now=1240343514.801921, set=1240343514.801921, ret=0, audt=3745 sf=1 2009-04-21 14:51:54.801 AO: Broadcasting free space avail 2009-04-21 14:51:54.817 GetAudiotime now=1240343514.817546, set=1240343514.801921, ret=15, audt=3745 sf=1 2009-04-21 14:51:54.817 GetAudiotime now=1240343514.817546, set=1240343514.801921, ret=15, audt=3745 sf=1 2009-04-21 14:51:54.817 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4738 needsupmix 0 2009-04-21 14:51:54.817 NVP: A/V Divergence: -1.2965, Rate: 0.0125877, Warpfactor: 0.989703, warpfactor_avg: 0.999973 2009-04-21 14:51:54.848 SetAudiotime set=1240343514.848797, audt=3776 atc=4764 tb=175104 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.848 GetAudiotime now=1240343514.848797, set=1240343514.848797, ret=0, audt=3776 sf=1 2009-04-21 14:51:54.848 AO: Broadcasting free space avail 2009-04-21 14:51:54.848 GetAudiotime now=1240343514.848797, set=1240343514.848797, ret=0, audt=3776 sf=1 2009-04-21 14:51:54.848 GetAudiotime now=1240343514.848797, set=1240343514.848797, ret=0, audt=3776 sf=1 2009-04-21 14:51:54.848 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4785 needsupmix 0 2009-04-21 14:51:54.848 NVP: A/V Divergence: -1.15968, Rate: 0.136816, Warpfactor: 0.990198, warpfactor_avg: 0.999973 2009-04-21 14:51:54.880 SetAudiotime set=1240343514.880047, audt=3832 atc=4811 tb=173568 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.880 GetAudiotime now=1240343514.880047, set=1240343514.880047, ret=0, audt=3832 sf=1 2009-04-21 14:51:54.880 AO: Broadcasting free space avail 2009-04-21 14:51:54.880 GetAudiotime now=1240343514.880047, set=1240343514.880047, ret=0, audt=3832 sf=1 2009-04-21 14:51:54.880 GetAudiotime now=1240343514.880047, set=1240343514.880047, ret=0, audt=3832 sf=1 2009-04-21 14:51:54.880 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=4808 needsupmix 0 2009-04-21 14:51:54.880 NVP: A/V Divergence: -1.2219, Rate: -0.062219, Warpfactor: 0.989703, warpfactor_avg: 0.999972 2009-04-21 14:51:54.911 SetAudiotime set=1240343514.911297, audt=3864 atc=4834 tb=172032 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.911 GetAudiotime now=1240343514.911297, set=1240343514.911297, ret=0, audt=3864 sf=1 2009-04-21 14:51:54.911 AO: Broadcasting free space avail 2009-04-21 14:51:54.911 GetAudiotime now=1240343514.911297, set=1240343514.911297, ret=0, audt=3864 sf=1 2009-04-21 14:51:54.911 GetAudiotime now=1240343514.911297, set=1240343514.911297, ret=0, audt=3864 sf=1 2009-04-21 14:51:54.911 AO: _AddSamples samples=1152 bytes=4608, used=73729, free=1462271, timecode=4831 needsupmix 0 2009-04-21 14:51:54.911 GetAudiotime now=1240343514.911297, set=1240343514.911297, ret=0, audt=3864 sf=1 2009-04-21 14:51:54.911 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4855 needsupmix 0 2009-04-21 14:51:54.926 NVP: A/V Divergence: -1.26107, Rate: -0.0391716, Warpfactor: 0.989571, warpfactor_avg: 0.999972 2009-04-21 14:51:54.942 SetAudiotime set=1240343514.942548, audt=3893 atc=4881 tb=175104 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.942 GetAudiotime now=1240343514.942548, set=1240343514.942548, ret=0, audt=3893 sf=1 2009-04-21 14:51:54.942 AO: Broadcasting free space avail 2009-04-21 14:51:54.942 GetAudiotime now=1240343514.942548, set=1240343514.942548, ret=0, audt=3893 sf=1 2009-04-21 14:51:54.942 GetAudiotime now=1240343514.942548, set=1240343514.942548, ret=0, audt=3893 sf=1 2009-04-21 14:51:54.942 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=4878 needsupmix 0 2009-04-21 14:51:54.958 NVP: A/V Divergence: -1.26047, Rate: 0.000599413, Warpfactor: 0.989893, warpfactor_avg: 0.999971 2009-04-21 14:51:54.989 GetAudiotime now=1240343514.989423, set=1240343514.942548, ret=46, audt=3893 sf=1 2009-04-21 14:51:54.989 GetAudiotime now=1240343514.989423, set=1240343514.942548, ret=46, audt=3893 sf=1 2009-04-21 14:51:54.989 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=4901 needsupmix 0 2009-04-21 14:51:54.989 GetAudiotime now=1240343514.989423, set=1240343514.942548, ret=46, audt=3893 sf=1 2009-04-21 14:51:54.989 AO: _AddSamples samples=1152 bytes=4608, used=86017, free=1449983, timecode=4924 needsupmix 0 2009-04-21 14:51:54.989 SetAudiotime set=1240343514.989423, audt=3919 atc=4950 tb=182784 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:54.989 GetAudiotime now=1240343514.989423, set=1240343514.989423, ret=0, audt=3919 sf=1 2009-04-21 14:51:54.989 AO: Broadcasting free space avail 2009-04-21 14:51:54.989 NVP: A/V Divergence: -1.34994, Rate: -0.0894623, Warpfactor: 0.989398, warpfactor_avg: 0.999971 2009-04-21 14:51:55.020 SetAudiotime set=1240343515.20674, audt=3954 atc=4950 tb=176640 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:55.020 GetAudiotime now=1240343515.20674, set=1240343515.20674, ret=0, audt=3954 sf=1 2009-04-21 14:51:55.020 AO: Broadcasting free space avail 2009-04-21 14:51:55.020 GetAudiotime now=1240343515.20674, set=1240343515.20674, ret=0, audt=3954 sf=1 2009-04-21 14:51:55.020 GetAudiotime now=1240343515.20674, set=1240343515.20674, ret=0, audt=3954 sf=1 2009-04-21 14:51:55.020 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=4948 needsupmix 0 2009-04-21 14:51:55.020 NVP: A/V Divergence: -1.28217, Rate: 0.0677636, Warpfactor: 0.989893, warpfactor_avg: 0.99997 2009-04-21 14:51:55.051 GetAudiotime now=1240343515.51924, set=1240343515.20674, ret=31, audt=3954 sf=1 2009-04-21 14:51:55.051 GetAudiotime now=1240343515.51924, set=1240343515.20674, ret=31, audt=3954 sf=1 2009-04-21 14:51:55.051 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=4994 needsupmix 0 2009-04-21 14:51:55.051 NVP: A/V Divergence: -1.21636, Rate: 0.0658155, Warpfactor: 0.990388, warpfactor_avg: 0.999969 2009-04-21 14:51:55.051 SetAudiotime set=1240343515.51924, audt=4006 atc=5020 tb=179712 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:55.051 GetAudiotime now=1240343515.51924, set=1240343515.51924, ret=0, audt=4006 sf=1 2009-04-21 14:51:55.051 AO: Broadcasting free space avail 2009-04-21 14:51:55.083 GetAudiotime now=1240343515.83175, set=1240343515.51924, ret=31, audt=4006 sf=1 2009-04-21 14:51:55.083 GetAudiotime now=1240343515.83175, set=1240343515.51924, ret=31, audt=4006 sf=1 2009-04-21 14:51:55.083 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=5017 needsupmix 0 2009-04-21 14:51:55.083 SetAudiotime set=1240343515.83175, audt=4038 atc=5043 tb=178176 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:55.083 GetAudiotime now=1240343515.83175, set=1240343515.83175, ret=0, audt=4038 sf=1 2009-04-21 14:51:55.083 AO: Broadcasting free space avail 2009-04-21 14:51:55.083 NVP: A/V Divergence: -1.30186, Rate: -0.0855062, Warpfactor: 0.989892, warpfactor_avg: 0.999969 2009-04-21 14:51:55.114 GetAudiotime now=1240343515.114425, set=1240343515.83175, ret=31, audt=4038 sf=1 2009-04-21 14:51:55.114 GetAudiotime now=1240343515.114425, set=1240343515.83175, ret=31, audt=4038 sf=1 2009-04-21 14:51:55.114 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=5041 needsupmix 0 2009-04-21 14:51:55.114 NVP: A/V Divergence: -1.35851, Rate: -0.0566445, Warpfactor: 0.989397, warpfactor_avg: 0.999968 2009-04-21 14:51:55.130 SetAudiotime set=1240343515.130050, audt=4071 atc=5067 tb=176640 sb=92160 eds=4430285 abps=4 sf=1 2009-04-21 14:51:55.130 GetAudiotime now=1240343515.130050, set=1240343515.130050, ret=0, audt=4071 sf=1 2009-04-21 14:51:55.130 AO: Broadcasting free space avail 2009-04-21 14:51:55.145 GetAudiotime now=1240343515.145675, set=1240343515.130050, ret=15, audt=4071 sf=1 2009-04-21 14:51:55.145 GetAudiotime now=1240343515.145675, set=1240343515.130050, ret=15, audt=4071 sf=1 2009-04-21 14:51:55.145 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=5064 needsupmix 0 2009-04-21 14:51:55.145 GetAudiotime now=1240343515.145675, set=1240343515.130050, ret=15, audt=4071 sf=1 2009-04-21 14:51:55.145 AO: _AddSamples samples=1152 bytes=4608, used=82945, free=1453055, timecode=5087 needsupmix 0 2009-04-21 14:51:55.145 AO: SetEffDsp: 4424940 2009-04-21 14:51:55.161 NVP: A/V Divergence: -1.28112, Rate: 0.0773842, Warpfactor: 0.989892, warpfactor_avg: 0.999968 2009-04-21 14:51:55.161 SetAudiotime set=1240343515.161301, audt=4098 atc=5113 tb=179712 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.161 GetAudiotime now=1240343515.161301, set=1240343515.161301, ret=0, audt=4098 sf=1 2009-04-21 14:51:55.161 AO: Broadcasting free space avail 2009-04-21 14:51:55.176 GetAudiotime now=1240343515.176926, set=1240343515.161301, ret=15, audt=4098 sf=1 2009-04-21 14:51:55.176 GetAudiotime now=1240343515.176926, set=1240343515.161301, ret=15, audt=4098 sf=1 2009-04-21 14:51:55.176 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=5110 needsupmix 0 2009-04-21 14:51:55.192 NVP: A/V Divergence: -1.17062, Rate: 0.110502, Warpfactor: 0.990387, warpfactor_avg: 0.999967 2009-04-21 14:51:55.192 SetAudiotime set=1240343515.192551, audt=4130 atc=5136 tb=178176 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.192 GetAudiotime now=1240343515.192551, set=1240343515.192551, ret=0, audt=4130 sf=1 2009-04-21 14:51:55.192 AO: Broadcasting free space avail 2009-04-21 14:51:55.223 GetAudiotime now=1240343515.223801, set=1240343515.192551, ret=31, audt=4130 sf=1 2009-04-21 14:51:55.223 GetAudiotime now=1240343515.223801, set=1240343515.192551, ret=31, audt=4130 sf=1 2009-04-21 14:51:55.223 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=5134 needsupmix 0 2009-04-21 14:51:55.223 GetAudiotime now=1240343515.223801, set=1240343515.192551, ret=31, audt=4130 sf=1 2009-04-21 14:51:55.223 AO: _AddSamples samples=1152 bytes=4608, used=84481, free=1451519, timecode=5157 needsupmix 0 2009-04-21 14:51:55.223 NVP: A/V Divergence: -1.20014, Rate: -0.0295211, Warpfactor: 0.99013, warpfactor_avg: 0.999967 2009-04-21 14:51:55.223 SetAudiotime set=1240343515.223801, audt=4159 atc=5183 tb=181248 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.223 GetAudiotime now=1240343515.223801, set=1240343515.223801, ret=0, audt=4159 sf=1 2009-04-21 14:51:55.223 AO: Broadcasting free space avail 2009-04-21 14:51:55.255 GetAudiotime now=1240343515.255052, set=1240343515.223801, ret=31, audt=4159 sf=1 2009-04-21 14:51:55.255 NVP: A/V Divergence: -1.18483, Rate: 0.015315, Warpfactor: 0.990611, warpfactor_avg: 0.999966 2009-04-21 14:51:55.270 SetAudiotime set=1240343515.270677, audt=4194 atc=5183 tb=175104 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.270 GetAudiotime now=1240343515.270677, set=1240343515.270677, ret=0, audt=4194 sf=1 2009-04-21 14:51:55.270 AO: Broadcasting free space avail 2009-04-21 14:51:55.286 GetAudiotime now=1240343515.286302, set=1240343515.270677, ret=15, audt=4194 sf=1 2009-04-21 14:51:55.286 GetAudiotime now=1240343515.286302, set=1240343515.270677, ret=15, audt=4194 sf=1 2009-04-21 14:51:55.286 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=5203 needsupmix 0 2009-04-21 14:51:55.286 GetAudiotime now=1240343515.286302, set=1240343515.270677, ret=15, audt=4194 sf=1 2009-04-21 14:51:55.286 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=5227 needsupmix 0 2009-04-21 14:51:55.286 NVP: A/V Divergence: -1.06842, Rate: 0.116406, Warpfactor: 0.991106, warpfactor_avg: 0.999966 2009-04-21 14:51:55.301 SetAudiotime set=1240343515.301927, audt=4247 atc=5253 tb=178176 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.301 GetAudiotime now=1240343515.301927, set=1240343515.301927, ret=0, audt=4247 sf=1 2009-04-21 14:51:55.301 AO: Broadcasting free space avail 2009-04-21 14:51:55.317 GetAudiotime now=1240343515.317553, set=1240343515.301927, ret=15, audt=4247 sf=1 2009-04-21 14:51:55.317 GetAudiotime now=1240343515.317553, set=1240343515.301927, ret=15, audt=4247 sf=1 2009-04-21 14:51:55.317 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=5250 needsupmix 0 2009-04-21 14:51:55.317 NVP: A/V Divergence: -1.13097, Rate: -0.0625487, Warpfactor: 0.990611, warpfactor_avg: 0.999965 2009-04-21 14:51:55.333 SetAudiotime set=1240343515.333178, audt=4279 atc=5276 tb=176640 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.333 GetAudiotime now=1240343515.333178, set=1240343515.333178, ret=0, audt=4279 sf=1 2009-04-21 14:51:55.333 AO: Broadcasting free space avail 2009-04-21 14:51:55.348 GetAudiotime now=1240343515.348803, set=1240343515.333178, ret=15, audt=4279 sf=1 2009-04-21 14:51:55.348 GetAudiotime now=1240343515.348803, set=1240343515.333178, ret=15, audt=4279 sf=1 2009-04-21 14:51:55.348 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=5273 needsupmix 0 2009-04-21 14:51:55.348 NVP: A/V Divergence: -1.16292, Rate: -0.0319487, Warpfactor: 0.990407, warpfactor_avg: 0.999965 2009-04-21 14:51:55.364 SetAudiotime set=1240343515.364428, audt=4310 atc=5299 tb=175104 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.364 GetAudiotime now=1240343515.364428, set=1240343515.364428, ret=0, audt=4310 sf=1 2009-04-21 14:51:55.364 AO: Broadcasting free space avail 2009-04-21 14:51:55.380 GetAudiotime now=1240343515.380053, set=1240343515.364428, ret=15, audt=4310 sf=1 2009-04-21 14:51:55.380 GetAudiotime now=1240343515.380053, set=1240343515.364428, ret=15, audt=4310 sf=1 2009-04-21 14:51:55.380 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=5297 needsupmix 0 2009-04-21 14:51:55.380 GetAudiotime now=1240343515.380053, set=1240343515.364428, ret=15, audt=4310 sf=1 2009-04-21 14:51:55.380 AO: _AddSamples samples=1152 bytes=4608, used=81409, free=1454591, timecode=5320 needsupmix 0 2009-04-21 14:51:55.380 NVP: A/V Divergence: -1.17188, Rate: -0.00896122, Warpfactor: 0.990518, warpfactor_avg: 0.999964 2009-04-21 14:51:55.411 SetAudiotime set=1240343515.411304, audt=4340 atc=5346 tb=178176 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.411 GetAudiotime now=1240343515.411304, set=1240343515.411304, ret=0, audt=4340 sf=1 2009-04-21 14:51:55.411 AO: Broadcasting free space avail 2009-04-21 14:51:55.411 GetAudiotime now=1240343515.411304, set=1240343515.411304, ret=0, audt=4340 sf=1 2009-04-21 14:51:55.411 GetAudiotime now=1240343515.411304, set=1240343515.411304, ret=0, audt=4340 sf=1 2009-04-21 14:51:55.411 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=5343 needsupmix 0 2009-04-21 14:51:55.426 NVP: A/V Divergence: -1.04373, Rate: 0.128154, Warpfactor: 0.991013, warpfactor_avg: 0.999964 2009-04-21 14:51:55.442 SetAudiotime set=1240343515.442554, audt=4372 atc=5369 tb=176640 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.442 GetAudiotime now=1240343515.442554, set=1240343515.442554, ret=0, audt=4372 sf=1 2009-04-21 14:51:55.442 AO: Broadcasting free space avail 2009-04-21 14:51:55.458 GetAudiotime now=1240343515.458179, set=1240343515.442554, ret=15, audt=4372 sf=1 2009-04-21 14:51:55.458 GetAudiotime now=1240343515.458179, set=1240343515.442554, ret=15, audt=4372 sf=1 2009-04-21 14:51:55.458 AO: _AddSamples samples=1152 bytes=4608, used=78337, free=1457663, timecode=5366 needsupmix 0 2009-04-21 14:51:55.458 NVP: A/V Divergence: -1.04502, Rate: -0.00128874, Warpfactor: 0.991509, warpfactor_avg: 0.999963 2009-04-21 14:51:55.473 SetAudiotime set=1240343515.473805, audt=4403 atc=5392 tb=175104 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.473 GetAudiotime now=1240343515.473805, set=1240343515.473805, ret=0, audt=4403 sf=1 2009-04-21 14:51:55.473 AO: Broadcasting free space avail 2009-04-21 14:51:55.473 TV: Attempting to change from WatchingLiveTV to None 2009-04-21 14:51:55.473 TV: StopStuff() -- begin 2009-04-21 14:51:55.473 TV: StopStuff(): stopping ring buffer[s] 2009-04-21 14:51:55.489 GetAudiotime now=1240343515.489430, set=1240343515.473805, ret=15, audt=4403 sf=1 2009-04-21 14:51:55.489 GetAudiotime now=1240343515.489430, set=1240343515.473805, ret=15, audt=4403 sf=1 2009-04-21 14:51:55.489 AO: _AddSamples samples=1152 bytes=4608, used=76801, free=1459199, timecode=5413 needsupmix 0 2009-04-21 14:51:55.489 NVP: A/V Divergence: -1.03102, Rate: 0.0139963, Warpfactor: 0.991827, warpfactor_avg: 0.999963 2009-04-21 14:51:55.505 SetAudiotime set=1240343515.505055, audt=4459 atc=5439 tb=173568 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.505 GetAudiotime now=1240343515.505055, set=1240343515.505055, ret=0, audt=4459 sf=1 2009-04-21 14:51:55.505 AO: Broadcasting free space avail 2009-04-21 14:51:55.520 GetAudiotime now=1240343515.520680, set=1240343515.505055, ret=15, audt=4459 sf=1 2009-04-21 14:51:55.520 GetAudiotime now=1240343515.520680, set=1240343515.505055, ret=15, audt=4459 sf=1 2009-04-21 14:51:55.520 AO: _AddSamples samples=1152 bytes=4608, used=75265, free=1460735, timecode=5436 needsupmix 0 2009-04-21 14:51:55.520 GetAudiotime now=1240343515.520680, set=1240343515.505055, ret=15, audt=4459 sf=1 2009-04-21 14:51:55.520 AO: _AddSamples samples=1152 bytes=4608, used=79873, free=1456127, timecode=5459 needsupmix 0 2009-04-21 14:51:55.520 NVP: A/V Divergence: -1.19283, Rate: -0.161811, Warpfactor: 0.991331, warpfactor_avg: 0.999962 2009-04-21 14:51:55.536 TV: StopStuff(): stopping player[s] (1/2) 2009-04-21 14:51:55.536 TV: StopStuff(): stopping recorder[s] 2009-04-21 14:51:55.536 NVP: Exited decoder loop. 2009-04-21 14:51:55.536 SetAudiotime set=1240343515.536305, audt=4488 atc=5485 tb=176640 sb=92160 eds=4424940 abps=4 sf=1 2009-04-21 14:51:55.536 GetAudiotime now=1240343515.536305, set=1240343515.536305, ret=0, audt=4488 sf=1 2009-04-21 14:51:55.536 AO: Broadcasting free space avail 2009-04-21 14:51:55.551 GetAudiotime now=1240343515.551931, set=1240343515.536305, ret=15, audt=4488 sf=1 2009-04-21 14:51:55.551 VideoOutputD3D: Exit() 2009-04-21 14:51:55.551 AO: Killing AudioOutputDSP 2009-04-21 14:51:55.567 AO: OutputAudioLoop: Stop Event 2009-04-21 14:51:55.567 AO: kickoffOutputAudioLoop exiting 2009-04-21 14:51:55.692 TV: StopStuff(): stopping player[s] (2/2) 2009-04-21 14:51:55.692 Connecting to backend server: 192.168.1.1:6543 (try 1 of 5) 2009-04-21 14:51:55.692 Using protocol version 40 2009-04-21 14:51:55.708 Event socket closed. No connection to the backend. 2009-04-21 14:51:55.723 TV: StopStuff() -- end 2009-04-21 14:51:55.723 TV: Changing from WatchingLiveTV to None 2009-04-21 14:51:56.255 Connecting to backend server: 192.168.1.1:6543 (try 1 of 5) 2009-04-21 14:51:56.255 Using protocol version 40 2009-04-21 14:51:56.270 Event socket closed. No connection to the backend. 2009-04-21 14:51:56.270 MythMainWindow::attach old: none, new: unnamed, thread: 536 qgl_win.cpp; QGLWidget::init( QGLContext *context, const QGLWidget* shareWidget ) qgl_win.cpp:QGLWidget::setContext(QGLContext *context,const QGLContext* shareContext,bool deleteOldContext) qgl_win.cpp:QGLContext::chooseContext() qgl_win.cpp:QGLWidget::reparent() Initialize Yadif Deinterlacer. In-Pixformat = 1 Out-Pixformat=1 qgl_win.cpp:QGLContext::doneCurrent() 2009-04-21 14:51:56.895 Deleting UPnP client... 2009-04-21 14:51:56.895 QMulticastSocket: setsockopt - IP_ADD_MEMBERSHIP Error