2008-10-04 16:53:04.530 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.996502, warpfactor_avg: 0.999996 2008-10-04 16:53:04.538 AFD: video timecode 3047121946 3047117441 33856860 33856843 2008-10-04 16:53:04.544 AFD: video timecode 3047118943 3047118943 33856877 33856860 2008-10-04 16:53:04.547 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.997, warpfactor_avg: 0.999996 2008-10-04 16:53:04.553 AFD: video timecode 3047120444 3047120444 33856893 33856877 2008-10-04 16:53:04.553 AFD: audio timecode 3047068579 3047068579 33856317 33856349 2008-10-04 16:53:04.553 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33856317 needsupmix 0 2008-10-04 16:53:04.554 AO: Broadcasting free space avail 2008-10-04 16:53:04.554 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.554 SetAudiotime set=1223164384.554885, audt=33856157 atc=33856349 tb=36864 sb=36864 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.556 SetAudiotime set=1223164384.556956, audt=33856160 atc=33856349 tb=36476 sb=36476 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.561 SetAudiotime set=1223164384.561376, audt=33856164 atc=33856349 tb=35624 sb=35624 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.563 GetAudiotime now=1223164384.563286, set=1223164384.561376, ret=1, audt=33856164 sf=1 2008-10-04 16:53:04.563 NVP: A/V Divergence: 4.66043, Rate: 4.66043, Warpfactor: 0.997499, warpfactor_avg: 0.999996 2008-10-04 16:53:04.563 SetAudiotime set=1223164384.563564, audt=33856166 atc=33856349 tb=35204 sb=35204 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.565 SetAudiotime set=1223164384.565647, audt=33856168 atc=33856349 tb=34804 sb=34804 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.566 AFD: video timecode 3047126450 3047121946 33856910 33856893 2008-10-04 16:53:04.566 AFD: audio timecode 3047071459 3047071459 33856349 33856381 2008-10-04 16:53:04.566 GetAudiotime now=1223164384.566756, set=1223164384.565647, ret=1, audt=33856168 sf=1 2008-10-04 16:53:04.566 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33856349 needsupmix 0 2008-10-04 16:53:04.567 AFD: audio timecode 3047074339 3047074339 33856381 33856413 2008-10-04 16:53:04.567 GetAudiotime now=1223164384.567285, set=1223164384.565647, ret=1, audt=33856168 sf=1 2008-10-04 16:53:04.567 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856381 needsupmix 0 2008-10-04 16:53:04.567 SetAudiotime set=1223164384.567799, audt=33856170 atc=33856413 tb=46680 sb=34392 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.567 GetAudiotime now=1223164384.567928, set=1223164384.567799, ret=0, audt=33856170 sf=1 2008-10-04 16:53:04.567 AFD: audio timecode 3047077219 3047077219 33856413 33856445 2008-10-04 16:53:04.567 AO: Broadcasting free space avail 2008-10-04 16:53:04.568 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.568 GetAudiotime now=1223164384.568042, set=1223164384.567799, ret=0, audt=33856170 sf=1 2008-10-04 16:53:04.568 SetAudiotime set=1223164384.568164, audt=33856171 atc=33856413 tb=46612 sb=40468 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.568 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856413 needsupmix 0 2008-10-04 16:53:04.568 GetAudiotime now=1223164384.568231, set=1223164384.568164, ret=0, audt=33856171 sf=1 2008-10-04 16:53:04.568 AO: Broadcasting free space avail 2008-10-04 16:53:04.568 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.568 SetAudiotime set=1223164384.568378, audt=33856171 atc=33856445 tb=52712 sb=46568 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.568 GetAudiotime now=1223164384.568432, set=1223164384.568378, ret=0, audt=33856171 sf=1 2008-10-04 16:53:04.568 AO: Broadcasting free space avail 2008-10-04 16:53:04.568 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.568 SetAudiotime set=1223164384.568557, audt=33856171 atc=33856445 tb=52680 sb=52680 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.568 AFD: audio timecode 3047080099 3047080099 33856445 33856477 2008-10-04 16:53:04.568 GetAudiotime now=1223164384.568955, set=1223164384.568557, ret=0, audt=33856171 sf=1 2008-10-04 16:53:04.569 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33856445 needsupmix 0 2008-10-04 16:53:04.569 AFD: audio timecode 3047082979 3047082979 33856477 33856509 2008-10-04 16:53:04.569 GetAudiotime now=1223164384.569679, set=1223164384.568557, ret=1, audt=33856171 sf=1 2008-10-04 16:53:04.569 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856477 needsupmix 0 2008-10-04 16:53:04.570 AFD: audio timecode 3047085859 3047085859 33856509 33856541 2008-10-04 16:53:04.570 GetAudiotime now=1223164384.570414, set=1223164384.568557, ret=1, audt=33856171 sf=1 2008-10-04 16:53:04.570 AO: _AddSamples samples=1536 bytes=6144, used=12289, free=1523711, timecode=33856509 needsupmix 0 2008-10-04 16:53:04.570 SetAudiotime set=1223164384.570625, audt=33856173 atc=33856541 tb=70712 sb=52280 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.570 GetAudiotime now=1223164384.570688, set=1223164384.570625, ret=0, audt=33856173 sf=1 2008-10-04 16:53:04.570 AO: Broadcasting free space avail 2008-10-04 16:53:04.570 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.570 SetAudiotime set=1223164384.570821, audt=33856173 atc=33856541 tb=70676 sb=58388 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.570 GetAudiotime now=1223164384.570873, set=1223164384.570821, ret=0, audt=33856173 sf=1 2008-10-04 16:53:04.570 AO: Broadcasting free space avail 2008-10-04 16:53:04.570 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.570 SetAudiotime set=1223164384.570994, audt=33856174 atc=33856541 tb=70644 sb=64500 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.571 AFD: audio timecode 3047088739 3047088739 33856541 33856573 2008-10-04 16:53:04.571 AO: audio waiting for space on soundcard: have 1036 need 6144 2008-10-04 16:53:04.571 GetAudiotime now=1223164384.571076, set=1223164384.570994, ret=0, audt=33856174 sf=1 2008-10-04 16:53:04.571 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856541 needsupmix 0 2008-10-04 16:53:04.571 AFD: audio timecode 3047091619 3047091619 33856573 33856605 2008-10-04 16:53:04.571 GetAudiotime now=1223164384.571472, set=1223164384.570994, ret=0, audt=33856174 sf=1 2008-10-04 16:53:04.571 AO: _AddSamples samples=1536 bytes=6144, used=12289, free=1523711, timecode=33856573 needsupmix 0 2008-10-04 16:53:04.572 AFD: audio timecode 3047094499 3047094499 33856605 33856637 2008-10-04 16:53:04.572 GetAudiotime now=1223164384.572248, set=1223164384.570994, ret=1, audt=33856174 sf=1 2008-10-04 16:53:04.572 AO: _AddSamples samples=1536 bytes=6144, used=18433, free=1517567, timecode=33856605 needsupmix 0 2008-10-04 16:53:04.572 AFD: audio timecode 3047097379 3047097379 33856637 33856669 2008-10-04 16:53:04.572 GetAudiotime now=1223164384.572798, set=1223164384.570994, ret=1, audt=33856174 sf=1 2008-10-04 16:53:04.572 AO: _AddSamples samples=1536 bytes=6144, used=24577, free=1511423, timecode=33856637 needsupmix 0 2008-10-04 16:53:04.573 AFD: audio timecode 3047100259 3047100259 33856669 33856701 2008-10-04 16:53:04.573 GetAudiotime now=1223164384.573435, set=1223164384.570994, ret=2, audt=33856174 sf=1 2008-10-04 16:53:04.573 AO: _AddSamples samples=1536 bytes=6144, used=30721, free=1505279, timecode=33856669 needsupmix 0 2008-10-04 16:53:04.574 AFD: audio timecode 3047103139 3047103139 33856701 33856733 2008-10-04 16:53:04.574 GetAudiotime now=1223164384.574282, set=1223164384.570994, ret=3, audt=33856174 sf=1 2008-10-04 16:53:04.574 AO: _AddSamples samples=1536 bytes=6144, used=36865, free=1499135, timecode=33856701 needsupmix 0 2008-10-04 16:53:04.574 AFD: audio timecode 3047106019 3047106019 33856733 33856765 2008-10-04 16:53:04.574 GetAudiotime now=1223164384.574960, set=1223164384.570994, ret=3, audt=33856174 sf=1 2008-10-04 16:53:04.575 AO: _AddSamples samples=1536 bytes=6144, used=43009, free=1492991, timecode=33856733 needsupmix 0 2008-10-04 16:53:04.575 AFD: audio timecode 3047108899 3047108899 33856765 33856797 2008-10-04 16:53:04.575 GetAudiotime now=1223164384.575716, set=1223164384.570994, ret=4, audt=33856174 sf=1 2008-10-04 16:53:04.575 AO: _AddSamples samples=1536 bytes=6144, used=49153, free=1486847, timecode=33856765 needsupmix 0 2008-10-04 16:53:04.576 AFD: audio timecode 3047111779 3047111779 33856797 33856829 2008-10-04 16:53:04.576 GetAudiotime now=1223164384.576450, set=1223164384.570994, ret=5, audt=33856174 sf=1 2008-10-04 16:53:04.576 AO: _AddSamples samples=1536 bytes=6144, used=55297, free=1480703, timecode=33856797 needsupmix 0 2008-10-04 16:53:04.577 AFD: audio timecode 3047114659 3047114659 33856829 33856861 2008-10-04 16:53:04.577 GetAudiotime now=1223164384.577076, set=1223164384.570994, ret=6, audt=33856174 sf=1 2008-10-04 16:53:04.577 AO: _AddSamples samples=1536 bytes=6144, used=61441, free=1474559, timecode=33856829 needsupmix 0 2008-10-04 16:53:04.577 AFD: audio timecode 3047117539 3047117539 33856861 33856893 2008-10-04 16:53:04.577 GetAudiotime now=1223164384.577707, set=1223164384.570994, ret=6, audt=33856174 sf=1 2008-10-04 16:53:04.577 AO: _AddSamples samples=1536 bytes=6144, used=67585, free=1468415, timecode=33856861 needsupmix 0 2008-10-04 16:53:04.578 AFD: audio timecode 3047120419 3047120419 33856893 33856925 2008-10-04 16:53:04.578 SetAudiotime set=1223164384.578127, audt=33856181 atc=33856893 tb=136856 sb=63128 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.578 AO: audio waiting for space on soundcard: have 2408 need 6144 2008-10-04 16:53:04.580 NVP: Video is 4.66043 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.580 GetAudiotime now=1223164384.580098, set=1223164384.578127, ret=1, audt=33856181 sf=1 2008-10-04 16:53:04.580 AO: _AddSamples samples=1536 bytes=6144, used=73729, free=1462271, timecode=33856893 needsupmix 0 2008-10-04 16:53:04.580 GetAudiotime now=1223164384.580223, set=1223164384.578127, ret=2, audt=33856181 sf=1 2008-10-04 16:53:04.580 NVP: A/V Divergence: 8.14074, Rate: 3.48031, Warpfactor: 0.997997, warpfactor_avg: 0.999996 2008-10-04 16:53:04.580 AFD: audio timecode 3047123299 3047123299 33856925 33856957 2008-10-04 16:53:04.580 GetAudiotime now=1223164384.580860, set=1223164384.578127, ret=2, audt=33856181 sf=1 2008-10-04 16:53:04.580 AO: _AddSamples samples=1536 bytes=6144, used=79873, free=1456127, timecode=33856925 needsupmix 0 2008-10-04 16:53:04.581 AFD: audio timecode 3047126179 3047126179 33856957 33856989 2008-10-04 16:53:04.581 GetAudiotime now=1223164384.581596, set=1223164384.578127, ret=3, audt=33856181 sf=1 2008-10-04 16:53:04.581 AO: _AddSamples samples=1536 bytes=6144, used=86017, free=1449983, timecode=33856957 needsupmix 0 2008-10-04 16:53:04.582 AFD: audio timecode 3047129059 3047129059 33856989 33857021 2008-10-04 16:53:04.582 GetAudiotime now=1223164384.582304, set=1223164384.578127, ret=4, audt=33856181 sf=1 2008-10-04 16:53:04.582 AO: _AddSamples samples=1536 bytes=6144, used=92161, free=1443839, timecode=33856989 needsupmix 0 2008-10-04 16:53:04.583 SetAudiotime set=1223164384.583278, audt=33856186 atc=33857021 tb=160444 sb=62140 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.583 AO: audio waiting for space on soundcard: have 3396 need 6144 2008-10-04 16:53:04.587 AFD: video timecode 3047123447 3047123447 33856927 33856910 2008-10-04 16:53:04.588 AFD: audio timecode 3047131939 3047131939 33857021 33857053 2008-10-04 16:53:04.588 GetAudiotime now=1223164384.588124, set=1223164384.583278, ret=4, audt=33856186 sf=1 2008-10-04 16:53:04.588 AO: _AddSamples samples=1536 bytes=6144, used=98305, free=1437695, timecode=33857021 needsupmix 0 2008-10-04 16:53:04.588 SetAudiotime set=1223164384.588378, audt=33856191 atc=33857053 tb=165608 sb=61160 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.588 AO: audio waiting for space on soundcard: have 4376 need 6144 2008-10-04 16:53:04.593 AFD: video timecode 3047124949 3047124949 33856943 33856927 2008-10-04 16:53:04.593 SetAudiotime set=1223164384.593478, audt=33856196 atc=33857053 tb=164628 sb=60180 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.593 AO: audio waiting for space on soundcard: have 5356 need 6144 2008-10-04 16:53:04.598 SetAudiotime set=1223164384.598877, audt=33856201 atc=33857053 tb=163592 sb=59144 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.599 GetAudiotime now=1223164384.599002, set=1223164384.598877, ret=0, audt=33856201 sf=1 2008-10-04 16:53:04.599 AO: Broadcasting free space avail 2008-10-04 16:53:04.599 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.599 SetAudiotime set=1223164384.599143, audt=33856202 atc=33857053 tb=163540 sb=65236 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.599 AO: audio waiting for space on soundcard: have 300 need 6144 2008-10-04 16:53:04.601 AFD: video timecode 3047130955 3047126450 33856960 0 2008-10-04 16:53:04.604 SetAudiotime set=1223164384.604247, audt=33856207 atc=33857053 tb=162560 sb=64256 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.604 AO: audio waiting for space on soundcard: have 1276 need 6144 2008-10-04 16:53:04.608 AFD: video timecode 3047127952 3047127952 33856977 33856960 2008-10-04 16:53:04.611 SetAudiotime set=1223164384.611423, audt=33856214 atc=33857053 tb=161184 sb=62880 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.611 AO: audio waiting for space on soundcard: have 2656 need 6144 2008-10-04 16:53:04.614 NVP: Video is 8.14074 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.614 GetAudiotime now=1223164384.614261, set=1223164384.611423, ret=2, audt=33856214 sf=1 2008-10-04 16:53:04.614 NVP: A/V Divergence: 10.5112, Rate: 2.3705, Warpfactor: 0.998496, warpfactor_avg: 0.999996 2008-10-04 16:53:04.616 SetAudiotime set=1223164384.616532, audt=33856219 atc=33857053 tb=160204 sb=61900 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.616 AO: audio waiting for space on soundcard: have 3636 need 6144 2008-10-04 16:53:04.621 SetAudiotime set=1223164384.621652, audt=33856224 atc=33857053 tb=159220 sb=60916 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.621 AO: audio waiting for space on soundcard: have 4620 need 6144 2008-10-04 16:53:04.622 AFD: video timecode 3047129453 3047129453 33856993 33856977 2008-10-04 16:53:04.626 SetAudiotime set=1223164384.626867, audt=33856229 atc=33857053 tb=158220 sb=59916 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.626 AO: audio waiting for space on soundcard: have 5620 need 6144 2008-10-04 16:53:04.629 AFD: video timecode 3047135459 3047130955 33857010 33856993 2008-10-04 16:53:04.632 SetAudiotime set=1223164384.632067, audt=33856235 atc=33857053 tb=157220 sb=58916 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.632 GetAudiotime now=1223164384.632182, set=1223164384.632067, ret=0, audt=33856235 sf=1 2008-10-04 16:53:04.632 AO: Broadcasting free space avail 2008-10-04 16:53:04.632 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.632 SetAudiotime set=1223164384.632319, audt=33856235 atc=33857053 tb=157172 sb=65012 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.632 AO: audio waiting for space on soundcard: have 524 need 6144 2008-10-04 16:53:04.637 SetAudiotime set=1223164384.637427, audt=33856240 atc=33857053 tb=156192 sb=64032 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.637 AO: audio waiting for space on soundcard: have 1504 need 6144 2008-10-04 16:53:04.644 SetAudiotime set=1223164384.644793, audt=33856247 atc=33857053 tb=154776 sb=62616 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.644 AO: audio waiting for space on soundcard: have 2920 need 6144 2008-10-04 16:53:04.646 NVP: Video is 10.5112 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.646 GetAudiotime now=1223164384.646558, set=1223164384.644793, ret=1, audt=33856247 sf=1 2008-10-04 16:53:04.646 NVP: A/V Divergence: 12.0493, Rate: 1.53809, Warpfactor: 0.998995, warpfactor_avg: 0.999996 2008-10-04 16:53:04.649 SetAudiotime set=1223164384.649923, audt=33856252 atc=33857053 tb=153792 sb=61632 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.649 AO: audio waiting for space on soundcard: have 3904 need 6144 2008-10-04 16:53:04.650 AFD: video timecode 3047132456 3047132456 33857027 33857010 2008-10-04 16:53:04.655 SetAudiotime set=1223164384.655041, audt=33856258 atc=33857053 tb=152808 sb=60648 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.655 AO: audio waiting for space on soundcard: have 4888 need 6144 2008-10-04 16:53:04.660 SetAudiotime set=1223164384.660170, audt=33856263 atc=33857053 tb=151824 sb=59664 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.660 AO: audio waiting for space on soundcard: have 5872 need 6144 2008-10-04 16:53:04.665 SetAudiotime set=1223164384.665350, audt=33856268 atc=33857053 tb=150828 sb=58668 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.665 GetAudiotime now=1223164384.665416, set=1223164384.665350, ret=0, audt=33856268 sf=1 2008-10-04 16:53:04.665 AO: Broadcasting free space avail 2008-10-04 16:53:04.665 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.665 SetAudiotime set=1223164384.665550, audt=33856268 atc=33857053 tb=150792 sb=64776 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.665 AO: audio waiting for space on soundcard: have 760 need 6144 2008-10-04 16:53:04.670 SetAudiotime set=1223164384.670666, audt=33856273 atc=33857053 tb=149808 sb=63792 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.670 AO: audio waiting for space on soundcard: have 1744 need 6144 2008-10-04 16:53:04.678 SetAudiotime set=1223164384.678161, audt=33856281 atc=33857053 tb=148368 sb=62352 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.678 AO: audio waiting for space on soundcard: have 3184 need 6144 2008-10-04 16:53:04.679 NVP: Video is 12.0493 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.679 GetAudiotime now=1223164384.679949, set=1223164384.678161, ret=1, audt=33856281 sf=1 2008-10-04 16:53:04.680 NVP: A/V Divergence: 12.9482, Rate: 0.898819, Warpfactor: 0.999495, warpfactor_avg: 0.999996 2008-10-04 16:53:04.683 SetAudiotime set=1223164384.683297, audt=33856286 atc=33857053 tb=147384 sb=61368 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.683 AO: audio waiting for space on soundcard: have 4168 need 6144 2008-10-04 16:53:04.684 AFD: video timecode 3047133958 3047133958 33857043 33857027 2008-10-04 16:53:04.688 SetAudiotime set=1223164384.688454, audt=33856291 atc=33857053 tb=146392 sb=60376 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.688 AO: audio waiting for space on soundcard: have 5160 need 6144 2008-10-04 16:53:04.693 SetAudiotime set=1223164384.693632, audt=33856296 atc=33857053 tb=145400 sb=59384 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.693 GetAudiotime now=1223164384.693692, set=1223164384.693632, ret=0, audt=33856296 sf=1 2008-10-04 16:53:04.693 AO: Broadcasting free space avail 2008-10-04 16:53:04.693 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.693 SetAudiotime set=1223164384.693838, audt=33856296 atc=33857053 tb=145360 sb=65488 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.693 AO: audio waiting for space on soundcard: have 48 need 6144 2008-10-04 16:53:04.698 SetAudiotime set=1223164384.698942, audt=33856302 atc=33857053 tb=144380 sb=64508 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.699 AO: audio waiting for space on soundcard: have 1028 need 6144 2008-10-04 16:53:04.704 SetAudiotime set=1223164384.704054, audt=33856307 atc=33857053 tb=143396 sb=63524 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.704 AO: audio waiting for space on soundcard: have 2012 need 6144 2008-10-04 16:53:04.711 SetAudiotime set=1223164384.711525, audt=33856314 atc=33857053 tb=141964 sb=62092 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.711 AO: audio waiting for space on soundcard: have 3444 need 6144 2008-10-04 16:53:04.712 NVP: Video is 12.9482 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.712 GetAudiotime now=1223164384.712824, set=1223164384.711525, ret=1, audt=33856314 sf=1 2008-10-04 16:53:04.712 NVP: A/V Divergence: 13.3825, Rate: 0.434334, Warpfactor: 0.999995, warpfactor_avg: 0.999996 2008-10-04 16:53:04.716 SetAudiotime set=1223164384.716651, audt=33856319 atc=33857053 tb=140980 sb=61108 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.716 AO: audio waiting for space on soundcard: have 4428 need 6144 2008-10-04 16:53:04.720 AFD: video timecode 3047139964 3047135459 33857060 33857043 2008-10-04 16:53:04.721 SetAudiotime set=1223164384.721850, audt=33856324 atc=33857053 tb=139980 sb=60108 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.721 AO: audio waiting for space on soundcard: have 5428 need 6144 2008-10-04 16:53:04.726 SetAudiotime set=1223164384.726981, audt=33856330 atc=33857053 tb=138996 sb=59124 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.727 GetAudiotime now=1223164384.727043, set=1223164384.726981, ret=0, audt=33856330 sf=1 2008-10-04 16:53:04.727 AO: Broadcasting free space avail 2008-10-04 16:53:04.727 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.727 SetAudiotime set=1223164384.727179, audt=33856330 atc=33857053 tb=138956 sb=65228 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.727 AO: audio waiting for space on soundcard: have 308 need 6144 2008-10-04 16:53:04.732 SetAudiotime set=1223164384.732299, audt=33856335 atc=33857053 tb=137972 sb=64244 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.732 AO: audio waiting for space on soundcard: have 1288 need 6144 2008-10-04 16:53:04.737 SetAudiotime set=1223164384.737479, audt=33856340 atc=33857053 tb=136980 sb=63252 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.737 AO: audio waiting for space on soundcard: have 2284 need 6144 2008-10-04 16:53:04.742 SetAudiotime set=1223164384.742880, audt=33856345 atc=33857053 tb=135944 sb=62216 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.742 AO: audio waiting for space on soundcard: have 3320 need 6144 2008-10-04 16:53:04.746 NVP: Video is 13.3825 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.746 GetAudiotime now=1223164384.746695, set=1223164384.742880, ret=3, audt=33856345 sf=1 2008-10-04 16:53:04.746 NVP: A/V Divergence: 13.4535, Rate: 0.0710304, Warpfactor: 1.00049, warpfactor_avg: 0.999996 2008-10-04 16:53:04.749 SetAudiotime set=1223164384.749279, audt=33856352 atc=33857053 tb=134712 sb=60984 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.749 AO: audio waiting for space on soundcard: have 4548 need 6144 2008-10-04 16:53:04.754 SetAudiotime set=1223164384.754486, audt=33856357 atc=33857053 tb=133712 sb=59984 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.754 AO: audio waiting for space on soundcard: have 5548 need 6144 2008-10-04 16:53:04.754 AFD: video timecode 3047136961 3047136961 33857077 33857060 2008-10-04 16:53:04.759 SetAudiotime set=1223164384.759681, audt=33856362 atc=33857053 tb=132716 sb=58988 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.759 GetAudiotime now=1223164384.759745, set=1223164384.759681, ret=0, audt=33856362 sf=1 2008-10-04 16:53:04.759 AO: Broadcasting free space avail 2008-10-04 16:53:04.759 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.759 SetAudiotime set=1223164384.759889, audt=33856362 atc=33857053 tb=132676 sb=65092 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.759 AO: audio waiting for space on soundcard: have 444 need 6144 2008-10-04 16:53:04.764 SetAudiotime set=1223164384.764992, audt=33856368 atc=33857053 tb=131696 sb=64112 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.765 AO: audio waiting for space on soundcard: have 1424 need 6144 2008-10-04 16:53:04.770 SetAudiotime set=1223164384.770119, audt=33856373 atc=33857053 tb=130712 sb=63128 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.770 AO: audio waiting for space on soundcard: have 2408 need 6144 2008-10-04 16:53:04.775 SetAudiotime set=1223164384.775228, audt=33856378 atc=33857053 tb=129732 sb=62148 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.775 AO: audio waiting for space on soundcard: have 3388 need 6144 2008-10-04 16:53:04.779 NVP: Video is 13.4535 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.779 GetAudiotime now=1223164384.779924, set=1223164384.775228, ret=4, audt=33856378 sf=1 2008-10-04 16:53:04.780 NVP: A/V Divergence: 13.252, Rate: -0.201523, Warpfactor: 1.00099, warpfactor_avg: 0.999996 2008-10-04 16:53:04.782 SetAudiotime set=1223164384.782263, audt=33856385 atc=33857053 tb=128380 sb=60796 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.782 AO: audio waiting for space on soundcard: have 4740 need 6144 2008-10-04 16:53:04.785 AFD: video timecode 3047138462 3047138462 33857094 33857077 2008-10-04 16:53:04.787 SetAudiotime set=1223164384.787462, audt=33856390 atc=33857053 tb=127380 sb=59796 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.787 AO: audio waiting for space on soundcard: have 5736 need 6144 2008-10-04 16:53:04.792 SetAudiotime set=1223164384.792589, audt=33856395 atc=33857053 tb=126396 sb=58812 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.792 GetAudiotime now=1223164384.792653, set=1223164384.792589, ret=0, audt=33856395 sf=1 2008-10-04 16:53:04.792 AO: Broadcasting free space avail 2008-10-04 16:53:04.792 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.792 SetAudiotime set=1223164384.792796, audt=33856395 atc=33857053 tb=126356 sb=64916 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.792 AO: audio waiting for space on soundcard: have 620 need 6144 2008-10-04 16:53:04.797 SetAudiotime set=1223164384.797899, audt=33856400 atc=33857053 tb=125376 sb=63936 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.797 AO: audio waiting for space on soundcard: have 1600 need 6144 2008-10-04 16:53:04.803 SetAudiotime set=1223164384.803008, audt=33856406 atc=33857053 tb=124396 sb=62956 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.803 AO: audio waiting for space on soundcard: have 2580 need 6144 2008-10-04 16:53:04.808 SetAudiotime set=1223164384.808121, audt=33856411 atc=33857053 tb=123416 sb=61976 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.808 AO: audio waiting for space on soundcard: have 3560 need 6144 2008-10-04 16:53:04.813 SetAudiotime set=1223164384.813227, audt=33856416 atc=33857053 tb=122436 sb=60996 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.813 AO: audio waiting for space on soundcard: have 4540 need 6144 2008-10-04 16:53:04.813 NVP: Video is 13.252 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.813 GetAudiotime now=1223164384.813801, set=1223164384.813227, ret=0, audt=33856416 sf=1 2008-10-04 16:53:04.813 NVP: A/V Divergence: 12.8461, Rate: -0.405862, Warpfactor: 1.0015, warpfactor_avg: 0.999996 2008-10-04 16:53:04.818 SetAudiotime set=1223164384.818350, audt=33856421 atc=33857053 tb=121452 sb=60012 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.818 AO: audio waiting for space on soundcard: have 5524 need 6144 2008-10-04 16:53:04.821 AFD: video timecode 3047144468 3047139964 33857110 33857094 2008-10-04 16:53:04.823 SetAudiotime set=1223164384.823544, audt=33856426 atc=33857053 tb=120452 sb=59012 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.823 GetAudiotime now=1223164384.823621, set=1223164384.823544, ret=0, audt=33856426 sf=1 2008-10-04 16:53:04.823 AO: Broadcasting free space avail 2008-10-04 16:53:04.823 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.823 SetAudiotime set=1223164384.823762, audt=33856426 atc=33857053 tb=120412 sb=65116 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.823 AO: audio waiting for space on soundcard: have 420 need 6144 2008-10-04 16:53:04.828 SetAudiotime set=1223164384.828943, audt=33856432 atc=33857053 tb=119416 sb=64120 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.829 AO: audio waiting for space on soundcard: have 1416 need 6144 2008-10-04 16:53:04.834 SetAudiotime set=1223164384.834063, audt=33856437 atc=33857053 tb=118436 sb=63140 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.834 AO: audio waiting for space on soundcard: have 2396 need 6144 2008-10-04 16:53:04.839 SetAudiotime set=1223164384.839174, audt=33856442 atc=33857053 tb=117452 sb=62156 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.839 AO: audio waiting for space on soundcard: have 3380 need 6144 2008-10-04 16:53:04.844 SetAudiotime set=1223164384.844282, audt=33856447 atc=33857053 tb=116472 sb=61176 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.844 AO: audio waiting for space on soundcard: have 4360 need 6144 2008-10-04 16:53:04.846 NVP: Video is 12.8461 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.846 GetAudiotime now=1223164384.846553, set=1223164384.844282, ret=2, audt=33856447 sf=1 2008-10-04 16:53:04.846 NVP: A/V Divergence: 12.287, Rate: -0.559132, Warpfactor: 1.002, warpfactor_avg: 0.999996 2008-10-04 16:53:04.849 SetAudiotime set=1223164384.849409, audt=33856452 atc=33857053 tb=115488 sb=60192 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.849 AO: audio waiting for space on soundcard: have 5344 need 6144 2008-10-04 16:53:04.854 SetAudiotime set=1223164384.854599, audt=33856457 atc=33857053 tb=114492 sb=59196 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.854 GetAudiotime now=1223164384.854729, set=1223164384.854599, ret=0, audt=33856457 sf=1 2008-10-04 16:53:04.854 AO: Broadcasting free space avail 2008-10-04 16:53:04.854 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.854 SetAudiotime set=1223164384.854867, audt=33856457 atc=33857053 tb=114440 sb=65288 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.854 AO: audio waiting for space on soundcard: have 248 need 6144 2008-10-04 16:53:04.855 AFD: video timecode 3047141465 3047141465 33857127 33857110 2008-10-04 16:53:04.859 SetAudiotime set=1223164384.859982, audt=33856463 atc=33857053 tb=113456 sb=64304 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.860 AO: audio waiting for space on soundcard: have 1232 need 6144 2008-10-04 16:53:04.865 SetAudiotime set=1223164384.865131, audt=33856468 atc=33857053 tb=112468 sb=63316 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.865 AO: audio waiting for space on soundcard: have 2220 need 6144 2008-10-04 16:53:04.870 SetAudiotime set=1223164384.870265, audt=33856473 atc=33857053 tb=111480 sb=62328 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.870 AO: audio waiting for space on soundcard: have 3204 need 6144 2008-10-04 16:53:04.875 SetAudiotime set=1223164384.875378, audt=33856478 atc=33857053 tb=110500 sb=61348 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.875 AO: audio waiting for space on soundcard: have 4188 need 6144 2008-10-04 16:53:04.879 NVP: Video is 12.287 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.879 GetAudiotime now=1223164384.879944, set=1223164384.875378, ret=4, audt=33856478 sf=1 2008-10-04 16:53:04.880 NVP: A/V Divergence: 11.6279, Rate: -0.659114, Warpfactor: 1.0025, warpfactor_avg: 0.999996 2008-10-04 16:53:04.882 SetAudiotime set=1223164384.882366, audt=33856485 atc=33857053 tb=109160 sb=60008 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.882 AO: audio waiting for space on soundcard: have 5528 need 6144 2008-10-04 16:53:04.885 AFD: video timecode 3047142967 3047142967 33857144 33857127 2008-10-04 16:53:04.887 SetAudiotime set=1223164384.887572, audt=33856490 atc=33857053 tb=108160 sb=59008 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.887 GetAudiotime now=1223164384.887653, set=1223164384.887572, ret=0, audt=33856490 sf=1 2008-10-04 16:53:04.887 AO: Broadcasting free space avail 2008-10-04 16:53:04.887 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.887 SetAudiotime set=1223164384.887792, audt=33856490 atc=33857053 tb=108116 sb=65108 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.887 AO: audio waiting for space on soundcard: have 428 need 6144 2008-10-04 16:53:04.892 SetAudiotime set=1223164384.892898, audt=33856495 atc=33857053 tb=107136 sb=64128 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.892 AO: audio waiting for space on soundcard: have 1408 need 6144 2008-10-04 16:53:04.898 SetAudiotime set=1223164384.898024, audt=33856501 atc=33857053 tb=106152 sb=63144 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.898 AO: audio waiting for space on soundcard: have 2392 need 6144 2008-10-04 16:53:04.903 SetAudiotime set=1223164384.903220, audt=33856506 atc=33857053 tb=105156 sb=62148 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.903 AO: audio waiting for space on soundcard: have 3388 need 6144 2008-10-04 16:53:04.908 SetAudiotime set=1223164384.908381, audt=33856511 atc=33857053 tb=104164 sb=61156 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.908 AO: audio waiting for space on soundcard: have 4380 need 6144 2008-10-04 16:53:04.913 NVP: Video is 11.6279 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.913 GetAudiotime now=1223164384.913321, set=1223164384.908381, ret=4, audt=33856511 sf=1 2008-10-04 16:53:04.913 NVP: A/V Divergence: 10.8938, Rate: -0.734101, Warpfactor: 1.003, warpfactor_avg: 0.999996 2008-10-04 16:53:04.916 SetAudiotime set=1223164384.916944, audt=33856520 atc=33857053 tb=102520 sb=59512 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.917 AO: audio waiting for space on soundcard: have 6024 need 6144 2008-10-04 16:53:04.921 AFD: video timecode 3047148973 3047144468 33857160 33857144 2008-10-04 16:53:04.922 SetAudiotime set=1223164384.922155, audt=33856525 atc=33857053 tb=101520 sb=58512 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.922 GetAudiotime now=1223164384.922236, set=1223164384.922155, ret=0, audt=33856525 sf=1 2008-10-04 16:53:04.922 AO: Broadcasting free space avail 2008-10-04 16:53:04.922 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.922 SetAudiotime set=1223164384.922383, audt=33856525 atc=33857053 tb=101476 sb=64612 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.922 AO: audio waiting for space on soundcard: have 924 need 6144 2008-10-04 16:53:04.927 SetAudiotime set=1223164384.927503, audt=33856530 atc=33857053 tb=100492 sb=63628 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.927 AO: audio waiting for space on soundcard: have 1908 need 6144 2008-10-04 16:53:04.932 SetAudiotime set=1223164384.932683, audt=33856535 atc=33857053 tb=99496 sb=62632 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.932 AO: audio waiting for space on soundcard: have 2904 need 6144 2008-10-04 16:53:04.937 SetAudiotime set=1223164384.937820, audt=33856540 atc=33857053 tb=98512 sb=61648 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.937 AO: audio waiting for space on soundcard: have 3888 need 6144 2008-10-04 16:53:04.942 SetAudiotime set=1223164384.942930, audt=33856546 atc=33857053 tb=97528 sb=60664 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.942 AO: audio waiting for space on soundcard: have 4872 need 6144 2008-10-04 16:53:04.946 NVP: Video is 10.8938 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.946 GetAudiotime now=1223164384.946648, set=1223164384.942930, ret=3, audt=33856546 sf=1 2008-10-04 16:53:04.946 NVP: A/V Divergence: 10.0734, Rate: -0.820356, Warpfactor: 1.0035, warpfactor_avg: 0.999996 2008-10-04 16:53:04.949 SetAudiotime set=1223164384.949055, audt=33856552 atc=33857053 tb=96352 sb=59488 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.949 AO: audio waiting for space on soundcard: have 6044 need 6144 2008-10-04 16:53:04.949 AFD: video timecode 3047145970 3047145970 33857177 33857160 2008-10-04 16:53:04.954 SetAudiotime set=1223164384.954264, audt=33856557 atc=33857053 tb=95352 sb=58488 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.954 GetAudiotime now=1223164384.954326, set=1223164384.954264, ret=0, audt=33856557 sf=1 2008-10-04 16:53:04.954 AO: Broadcasting free space avail 2008-10-04 16:53:04.954 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.954 SetAudiotime set=1223164384.954470, audt=33856557 atc=33857053 tb=95312 sb=64592 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.954 AO: audio waiting for space on soundcard: have 944 need 6144 2008-10-04 16:53:04.959 SetAudiotime set=1223164384.959592, audt=33856562 atc=33857053 tb=94328 sb=63608 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.959 AO: audio waiting for space on soundcard: have 1924 need 6144 2008-10-04 16:53:04.964 SetAudiotime set=1223164384.964773, audt=33856567 atc=33857053 tb=93336 sb=62616 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.964 AO: audio waiting for space on soundcard: have 2920 need 6144 2008-10-04 16:53:04.969 SetAudiotime set=1223164384.969937, audt=33856573 atc=33857053 tb=92344 sb=61624 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.970 AO: audio waiting for space on soundcard: have 3912 need 6144 2008-10-04 16:53:04.975 SetAudiotime set=1223164384.975141, audt=33856578 atc=33857053 tb=91344 sb=60624 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.975 AO: audio waiting for space on soundcard: have 4912 need 6144 2008-10-04 16:53:04.980 NVP: Video is 10.0734 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:04.980 GetAudiotime now=1223164384.980222, set=1223164384.975141, ret=5, audt=33856578 sf=1 2008-10-04 16:53:04.980 NVP: A/V Divergence: 9.20344, Rate: -0.869987, Warpfactor: 1.004, warpfactor_avg: 0.999997 2008-10-04 16:53:04.982 SetAudiotime set=1223164384.982727, audt=33856585 atc=33857053 tb=89888 sb=59168 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.982 GetAudiotime now=1223164384.982878, set=1223164384.982727, ret=0, audt=33856585 sf=1 2008-10-04 16:53:04.982 AO: Broadcasting free space avail 2008-10-04 16:53:04.982 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:04.983 SetAudiotime set=1223164384.983034, audt=33856586 atc=33857053 tb=89828 sb=65252 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.983 AO: audio waiting for space on soundcard: have 284 need 6144 2008-10-04 16:53:04.983 AFD: video timecode 3047147471 3047147471 33857194 33857177 2008-10-04 16:53:04.988 SetAudiotime set=1223164384.988178, audt=33856591 atc=33857053 tb=88840 sb=64264 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.988 AO: audio waiting for space on soundcard: have 1272 need 6144 2008-10-04 16:53:04.993 SetAudiotime set=1223164384.993364, audt=33856596 atc=33857053 tb=87844 sb=63268 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.993 AO: audio waiting for space on soundcard: have 2268 need 6144 2008-10-04 16:53:04.998 SetAudiotime set=1223164384.998482, audt=33856601 atc=33857053 tb=86864 sb=62288 eds=4800000 abps=4 sf=1 2008-10-04 16:53:04.998 AO: audio waiting for space on soundcard: have 3248 need 6144 2008-10-04 16:53:05.003 SetAudiotime set=1223164385.3591, audt=33856606 atc=33857053 tb=85880 sb=61304 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.003 AO: audio waiting for space on soundcard: have 4232 need 6144 2008-10-04 16:53:05.008 SetAudiotime set=1223164385.8711, audt=33856611 atc=33857053 tb=84900 sb=60324 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.008 AO: audio waiting for space on soundcard: have 5212 need 6144 2008-10-04 16:53:05.013 NVP: Video is 9.20344 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.013 GetAudiotime now=1223164385.13520, set=1223164385.8711, ret=4, audt=33856611 sf=1 2008-10-04 16:53:05.013 NVP: A/V Divergence: 8.32614, Rate: -0.8773, Warpfactor: 1.0045, warpfactor_avg: 0.999997 2008-10-04 16:53:05.017 SetAudiotime set=1223164385.17397, audt=33856620 atc=33857053 tb=83232 sb=58656 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.017 GetAudiotime now=1223164385.17543, set=1223164385.17397, ret=0, audt=33856620 sf=1 2008-10-04 16:53:05.017 AO: Broadcasting free space avail 2008-10-04 16:53:05.017 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:05.017 SetAudiotime set=1223164385.17700, audt=33856620 atc=33857053 tb=83172 sb=64740 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.017 AO: audio waiting for space on soundcard: have 796 need 6144 2008-10-04 16:53:05.017 AFD: video timecode 3047153477 3047148973 33857210 0 2008-10-04 16:53:05.022 SetAudiotime set=1223164385.22831, audt=33856625 atc=33857053 tb=82188 sb=63756 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.022 AO: audio waiting for space on soundcard: have 1780 need 6144 2008-10-04 16:53:05.028 SetAudiotime set=1223164385.28019, audt=33856631 atc=33857053 tb=81192 sb=62760 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.028 AO: audio waiting for space on soundcard: have 2776 need 6144 2008-10-04 16:53:05.033 SetAudiotime set=1223164385.33151, audt=33856636 atc=33857053 tb=80204 sb=61772 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.033 AO: audio waiting for space on soundcard: have 3764 need 6144 2008-10-04 16:53:05.038 SetAudiotime set=1223164385.38288, audt=33856641 atc=33857053 tb=79220 sb=60788 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.038 AO: audio waiting for space on soundcard: have 4748 need 6144 2008-10-04 16:53:05.043 SetAudiotime set=1223164385.43454, audt=33856646 atc=33857053 tb=78228 sb=59796 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.043 AO: audio waiting for space on soundcard: have 5740 need 6144 2008-10-04 16:53:05.046 NVP: Video is 8.32614 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.046 GetAudiotime now=1223164385.46900, set=1223164385.43454, ret=3, audt=33856646 sf=1 2008-10-04 16:53:05.046 NVP: A/V Divergence: 7.39843, Rate: -0.927711, Warpfactor: 1.00501, warpfactor_avg: 0.999997 2008-10-04 16:53:05.050 SetAudiotime set=1223164385.49992, audt=33856653 atc=33857053 tb=76972 sb=58540 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.050 GetAudiotime now=1223164385.50151, set=1223164385.49992, ret=0, audt=33856653 sf=1 2008-10-04 16:53:05.050 AO: Broadcasting free space avail 2008-10-04 16:53:05.050 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:05.050 SetAudiotime set=1223164385.50306, audt=33856653 atc=33857053 tb=76912 sb=64624 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.050 AO: audio waiting for space on soundcard: have 912 need 6144 2008-10-04 16:53:05.051 AFD: video timecode 3047150474 3047150474 33857227 33857210 2008-10-04 16:53:05.055 SetAudiotime set=1223164385.55438, audt=33856658 atc=33857053 tb=75924 sb=63636 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.055 AO: audio waiting for space on soundcard: have 1896 need 6144 2008-10-04 16:53:05.060 SetAudiotime set=1223164385.60624, audt=33856663 atc=33857053 tb=74928 sb=62640 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.060 AO: audio waiting for space on soundcard: have 2892 need 6144 2008-10-04 16:53:05.065 SetAudiotime set=1223164385.65740, audt=33856668 atc=33857053 tb=73948 sb=61660 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.065 AO: audio waiting for space on soundcard: have 3876 need 6144 2008-10-04 16:53:05.070 SetAudiotime set=1223164385.70853, audt=33856673 atc=33857053 tb=72964 sb=60676 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.070 AO: audio waiting for space on soundcard: have 4856 need 6144 2008-10-04 16:53:05.075 SetAudiotime set=1223164385.75964, audt=33856679 atc=33857053 tb=71984 sb=59696 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.076 AO: audio waiting for space on soundcard: have 5840 need 6144 2008-10-04 16:53:05.080 NVP: Video is 7.39843 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.080 GetAudiotime now=1223164385.80277, set=1223164385.75964, ret=4, audt=33856679 sf=1 2008-10-04 16:53:05.080 NVP: A/V Divergence: 6.44794, Rate: -0.950489, Warpfactor: 1.00551, warpfactor_avg: 0.999997 2008-10-04 16:53:05.083 SetAudiotime set=1223164385.83224, audt=33856686 atc=33857053 tb=70592 sb=58304 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.083 GetAudiotime now=1223164385.83371, set=1223164385.83224, ret=0, audt=33856686 sf=1 2008-10-04 16:53:05.083 AO: Broadcasting free space avail 2008-10-04 16:53:05.083 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:05.083 SetAudiotime set=1223164385.83527, audt=33856686 atc=33857053 tb=70532 sb=64388 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.083 AO: audio waiting for space on soundcard: have 1148 need 6144 2008-10-04 16:53:05.084 AFD: video timecode 3047151976 3047151976 33857244 33857227 2008-10-04 16:53:05.088 SetAudiotime set=1223164385.88658, audt=33856691 atc=33857053 tb=69548 sb=63404 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.088 AO: audio waiting for space on soundcard: have 2132 need 6144 2008-10-04 16:53:05.093 SetAudiotime set=1223164385.93840, audt=33856696 atc=33857053 tb=68552 sb=62408 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.093 AO: audio waiting for space on soundcard: have 3128 need 6144 2008-10-04 16:53:05.098 SetAudiotime set=1223164385.98969, audt=33856702 atc=33857053 tb=67568 sb=61424 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.099 AO: audio waiting for space on soundcard: have 4112 need 6144 2008-10-04 16:53:05.104 SetAudiotime set=1223164385.104166, audt=33856707 atc=33857053 tb=66568 sb=60424 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.104 AO: audio waiting for space on soundcard: have 5112 need 6144 2008-10-04 16:53:05.109 SetAudiotime set=1223164385.109282, audt=33856712 atc=33857053 tb=65588 sb=59444 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.109 AO: audio waiting for space on soundcard: have 6092 need 6144 2008-10-04 16:53:05.113 NVP: Video is 6.44794 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.113 GetAudiotime now=1223164385.113666, set=1223164385.109282, ret=4, audt=33856712 sf=1 2008-10-04 16:53:05.113 NVP: A/V Divergence: 5.49529, Rate: -0.952646, Warpfactor: 1.00601, warpfactor_avg: 0.999997 2008-10-04 16:53:05.116 SetAudiotime set=1223164385.116661, audt=33856719 atc=33857053 tb=64172 sb=58028 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.116 GetAudiotime now=1223164385.116805, set=1223164385.116661, ret=0, audt=33856719 sf=1 2008-10-04 16:53:05.116 AO: Broadcasting free space avail 2008-10-04 16:53:05.116 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:05.116 SetAudiotime set=1223164385.116957, audt=33856720 atc=33857053 tb=64112 sb=64112 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.117 AFD: video timecode 3047157982 3047153477 33857260 33857244 2008-10-04 16:53:05.119 SetAudiotime set=1223164385.119040, audt=33856722 atc=33857053 tb=63712 sb=63712 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.121 SetAudiotime set=1223164385.121147, audt=33856724 atc=33857053 tb=63308 sb=63308 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.123 SetAudiotime set=1223164385.123227, audt=33856726 atc=33857053 tb=62908 sb=62908 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.125 SetAudiotime set=1223164385.125306, audt=33856728 atc=33857053 tb=62512 sb=62512 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.127 SetAudiotime set=1223164385.127387, audt=33856730 atc=33857053 tb=62112 sb=62112 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.129 SetAudiotime set=1223164385.129460, audt=33856732 atc=33857053 tb=61712 sb=61712 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.131 SetAudiotime set=1223164385.131534, audt=33856734 atc=33857053 tb=61316 sb=61316 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.133 SetAudiotime set=1223164385.133608, audt=33856736 atc=33857053 tb=60916 sb=60916 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.135 SetAudiotime set=1223164385.135682, audt=33856738 atc=33857053 tb=60516 sb=60516 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.137 SetAudiotime set=1223164385.137755, audt=33856740 atc=33857053 tb=60120 sb=60120 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.139 SetAudiotime set=1223164385.139825, audt=33856742 atc=33857053 tb=59724 sb=59724 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.141 SetAudiotime set=1223164385.141899, audt=33856745 atc=33857053 tb=59324 sb=59324 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.143 SetAudiotime set=1223164385.143991, audt=33856747 atc=33857053 tb=58924 sb=58924 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.146 SetAudiotime set=1223164385.146143, audt=33856749 atc=33857053 tb=58508 sb=58508 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.148 SetAudiotime set=1223164385.148413, audt=33856751 atc=33857053 tb=58072 sb=58072 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.148 NVP: Video is 5.49529 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.148 GetAudiotime now=1223164385.148796, set=1223164385.148413, ret=0, audt=33856751 sf=1 2008-10-04 16:53:05.148 NVP: A/V Divergence: 4.51106, Rate: -0.984235, Warpfactor: 1.00651, warpfactor_avg: 0.999997 2008-10-04 16:53:05.151 SetAudiotime set=1223164385.151518, audt=33856754 atc=33857053 tb=57476 sb=57476 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.152 AFD: video timecode 3047154979 3047154979 33857277 33857260 2008-10-04 16:53:05.153 SetAudiotime set=1223164385.153681, audt=33856756 atc=33857053 tb=57060 sb=57060 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.155 SetAudiotime set=1223164385.155773, audt=33856758 atc=33857053 tb=56660 sb=56660 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.157 SetAudiotime set=1223164385.157921, audt=33856761 atc=33857053 tb=56248 sb=56248 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.160 SetAudiotime set=1223164385.160092, audt=33856763 atc=33857053 tb=55832 sb=55832 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.162 SetAudiotime set=1223164385.162181, audt=33856765 atc=33857053 tb=55428 sb=55428 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.164 SetAudiotime set=1223164385.164257, audt=33856767 atc=33857053 tb=55032 sb=55032 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.166 SetAudiotime set=1223164385.166334, audt=33856769 atc=33857053 tb=54632 sb=54632 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.168 SetAudiotime set=1223164385.168410, audt=33856771 atc=33857053 tb=54232 sb=54232 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.170 SetAudiotime set=1223164385.170498, audt=33856773 atc=33857053 tb=53832 sb=53832 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.172 SetAudiotime set=1223164385.172626, audt=33856775 atc=33857053 tb=53424 sb=53424 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.174 SetAudiotime set=1223164385.174704, audt=33856777 atc=33857053 tb=53024 sb=53024 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.176 SetAudiotime set=1223164385.176785, audt=33856779 atc=33857053 tb=52624 sb=52624 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.178 SetAudiotime set=1223164385.178856, audt=33856781 atc=33857053 tb=52228 sb=52228 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.180 NVP: Video is 4.51106 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.180 GetAudiotime now=1223164385.180300, set=1223164385.178856, ret=1, audt=33856781 sf=1 2008-10-04 16:53:05.180 NVP: A/V Divergence: 3.5481, Rate: -0.962956, Warpfactor: 1.00702, warpfactor_avg: 0.999997 2008-10-04 16:53:05.182 SetAudiotime set=1223164385.182922, audt=33856786 atc=33857053 tb=51448 sb=51448 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.183 AFD: video timecode 3047156480 3047156480 33857294 33857277 2008-10-04 16:53:05.185 SetAudiotime set=1223164385.185088, audt=33856788 atc=33857053 tb=51032 sb=51032 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.187 SetAudiotime set=1223164385.187166, audt=33856790 atc=33857053 tb=50632 sb=50632 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.189 SetAudiotime set=1223164385.189241, audt=33856792 atc=33857053 tb=50232 sb=50232 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.191 SetAudiotime set=1223164385.191319, audt=33856794 atc=33857053 tb=49836 sb=49836 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.193 SetAudiotime set=1223164385.193398, audt=33856796 atc=33857053 tb=49436 sb=49436 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.195 SetAudiotime set=1223164385.195475, audt=33856798 atc=33857053 tb=49036 sb=49036 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.197 SetAudiotime set=1223164385.197552, audt=33856800 atc=33857053 tb=48636 sb=48636 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.199 SetAudiotime set=1223164385.199633, audt=33856802 atc=33857053 tb=48240 sb=48240 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.201 SetAudiotime set=1223164385.201746, audt=33856804 atc=33857053 tb=47832 sb=47832 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.203 SetAudiotime set=1223164385.203886, audt=33856807 atc=33857053 tb=47420 sb=47420 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.205 SetAudiotime set=1223164385.205975, audt=33856809 atc=33857053 tb=47020 sb=47020 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.208 SetAudiotime set=1223164385.208054, audt=33856811 atc=33857053 tb=46620 sb=46620 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.210 SetAudiotime set=1223164385.210142, audt=33856813 atc=33857053 tb=46220 sb=46220 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.212 SetAudiotime set=1223164385.212218, audt=33856815 atc=33857053 tb=45820 sb=45820 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.213 NVP: Video is 3.5481 frames ahead of audio, doubling video frame interval to slow down. 2008-10-04 16:53:05.213 GetAudiotime now=1223164385.213915, set=1223164385.212218, ret=1, audt=33856815 sf=1 2008-10-04 16:53:05.214 NVP: A/V Divergence: 2.57112, Rate: -0.976983, Warpfactor: 1.00752, warpfactor_avg: 0.999998 2008-10-04 16:53:05.216 SetAudiotime set=1223164385.216137, audt=33856819 atc=33857053 tb=45068 sb=45068 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.216 AFD: video timecode 3047162486 3047157982 33857310 33857294 2008-10-04 16:53:05.218 SetAudiotime set=1223164385.218308, audt=33856821 atc=33857053 tb=44652 sb=44652 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.220 SetAudiotime set=1223164385.220397, audt=33856823 atc=33857053 tb=44252 sb=44252 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.222 SetAudiotime set=1223164385.222473, audt=33856825 atc=33857053 tb=43852 sb=43852 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.224 SetAudiotime set=1223164385.224557, audt=33856827 atc=33857053 tb=43452 sb=43452 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.226 SetAudiotime set=1223164385.226643, audt=33856829 atc=33857053 tb=43052 sb=43052 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.228 SetAudiotime set=1223164385.228733, audt=33856831 atc=33857053 tb=42652 sb=42652 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.230 SetAudiotime set=1223164385.230877, audt=33856833 atc=33857053 tb=42240 sb=42240 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.233 SetAudiotime set=1223164385.233010, audt=33856836 atc=33857053 tb=41828 sb=41828 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.235 SetAudiotime set=1223164385.235110, audt=33856838 atc=33857053 tb=41424 sb=41424 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.237 SetAudiotime set=1223164385.237185, audt=33856840 atc=33857053 tb=41028 sb=41028 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.239 SetAudiotime set=1223164385.239276, audt=33856842 atc=33857053 tb=40628 sb=40628 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.241 SetAudiotime set=1223164385.241355, audt=33856844 atc=33857053 tb=40228 sb=40228 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.243 SetAudiotime set=1223164385.243434, audt=33856846 atc=33857053 tb=39828 sb=39828 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.245 SetAudiotime set=1223164385.245512, audt=33856848 atc=33857053 tb=39428 sb=39428 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.246 GetAudiotime now=1223164385.246675, set=1223164385.245512, ret=1, audt=33856848 sf=1 2008-10-04 16:53:05.246 NVP: A/V Divergence: 1.59863, Rate: -0.972487, Warpfactor: 1.00702, warpfactor_avg: 0.999998 2008-10-04 16:53:05.249 SetAudiotime set=1223164385.249249, audt=33856852 atc=33857053 tb=38712 sb=38712 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.249 AFD: video timecode 3047159483 3047159483 33857327 33857310 2008-10-04 16:53:05.251 SetAudiotime set=1223164385.251423, audt=33856854 atc=33857053 tb=38292 sb=38292 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.253 SetAudiotime set=1223164385.253503, audt=33856856 atc=33857053 tb=37896 sb=37896 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.255 SetAudiotime set=1223164385.255584, audt=33856858 atc=33857053 tb=37496 sb=37496 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.257 SetAudiotime set=1223164385.257669, audt=33856860 atc=33857053 tb=37096 sb=37096 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.259 SetAudiotime set=1223164385.259815, audt=33856862 atc=33857053 tb=36684 sb=36684 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.261 SetAudiotime set=1223164385.261964, audt=33856865 atc=33857053 tb=36268 sb=36268 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.264 SetAudiotime set=1223164385.264063, audt=33856867 atc=33857053 tb=35868 sb=35868 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.266 SetAudiotime set=1223164385.266145, audt=33856869 atc=33857053 tb=35468 sb=35468 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.268 SetAudiotime set=1223164385.268223, audt=33856871 atc=33857053 tb=35068 sb=35068 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.270 SetAudiotime set=1223164385.270305, audt=33856873 atc=33857053 tb=34668 sb=34668 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.272 SetAudiotime set=1223164385.272415, audt=33856875 atc=33857053 tb=34264 sb=34264 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.274 SetAudiotime set=1223164385.274555, audt=33856877 atc=33857053 tb=33852 sb=33852 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.276 SetAudiotime set=1223164385.276647, audt=33856879 atc=33857053 tb=33452 sb=33452 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.278 SetAudiotime set=1223164385.278721, audt=33856881 atc=33857053 tb=33052 sb=33052 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.279 GetAudiotime now=1223164385.279975, set=1223164385.278721, ret=1, audt=33856881 sf=1 2008-10-04 16:53:05.280 NVP: A/V Divergence: 0.614518, Rate: -0.984116, Warpfactor: 1.00651, warpfactor_avg: 0.999998 2008-10-04 16:53:05.282 AFD: video timecode 3047160985 3047160985 33857344 33857327 2008-10-04 16:53:05.282 SetAudiotime set=1223164385.282463, audt=33856885 atc=33857053 tb=32332 sb=32332 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.284 SetAudiotime set=1223164385.284563, audt=33856887 atc=33857053 tb=31932 sb=31932 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.286 SetAudiotime set=1223164385.286651, audt=33856889 atc=33857053 tb=31528 sb=31528 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.288 SetAudiotime set=1223164385.288788, audt=33856891 atc=33857053 tb=31120 sb=31120 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.290 SetAudiotime set=1223164385.290916, audt=33856894 atc=33857053 tb=30712 sb=30712 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.293 SetAudiotime set=1223164385.293015, audt=33856896 atc=33857053 tb=30308 sb=30308 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.295 SetAudiotime set=1223164385.295099, audt=33856898 atc=33857053 tb=29908 sb=29908 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.296 GetAudiotime now=1223164385.296550, set=1223164385.295099, ret=1, audt=33856898 sf=1 2008-10-04 16:53:05.296 NVP: A/V Divergence: -0.123539, Rate: -0.738057, Warpfactor: 1.00601, warpfactor_avg: 0.999998 2008-10-04 16:53:05.299 SetAudiotime set=1223164385.299286, audt=33856902 atc=33857053 tb=29104 sb=29104 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.299 AFD: video timecode 3047166991 3047162486 33857360 33857344 2008-10-04 16:53:05.301 SetAudiotime set=1223164385.301458, audt=33856904 atc=33857053 tb=28688 sb=28688 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.303 SetAudiotime set=1223164385.303535, audt=33856906 atc=33857053 tb=28288 sb=28288 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.305 SetAudiotime set=1223164385.305620, audt=33856908 atc=33857053 tb=27888 sb=27888 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.307 SetAudiotime set=1223164385.307699, audt=33856910 atc=33857053 tb=27488 sb=27488 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.309 SetAudiotime set=1223164385.309795, audt=33856912 atc=33857053 tb=27084 sb=27084 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.311 SetAudiotime set=1223164385.311869, audt=33856914 atc=33857053 tb=26688 sb=26688 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.313 GetAudiotime now=1223164385.313400, set=1223164385.311869, ret=1, audt=33856914 sf=1 2008-10-04 16:53:05.313 NVP: A/V Divergence: -0.662051, Rate: -0.538512, Warpfactor: 1.00551, warpfactor_avg: 0.999998 2008-10-04 16:53:05.315 AFD: video timecode 3047163988 3047163988 33857377 33857360 2008-10-04 16:53:05.315 SetAudiotime set=1223164385.315781, audt=33856918 atc=33857053 tb=25936 sb=25936 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.317 SetAudiotime set=1223164385.317892, audt=33856921 atc=33857053 tb=25532 sb=25532 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.320 SetAudiotime set=1223164385.320041, audt=33856923 atc=33857053 tb=25120 sb=25120 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.322 SetAudiotime set=1223164385.322187, audt=33856925 atc=33857053 tb=24704 sb=24704 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.324 SetAudiotime set=1223164385.324271, audt=33856927 atc=33857053 tb=24304 sb=24304 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.326 SetAudiotime set=1223164385.326355, audt=33856929 atc=33857053 tb=23904 sb=23904 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.328 SetAudiotime set=1223164385.328428, audt=33856931 atc=33857053 tb=23508 sb=23508 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.330 GetAudiotime now=1223164385.330048, set=1223164385.328428, ret=1, audt=33856931 sf=1 2008-10-04 16:53:05.330 NVP: A/V Divergence: -1.08092, Rate: -0.418869, Warpfactor: 1.005, warpfactor_avg: 0.999999 2008-10-04 16:53:05.332 SetAudiotime set=1223164385.332752, audt=33856935 atc=33857053 tb=22676 sb=22676 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.333 AFD: video timecode 3047165489 3047165489 33857394 33857377 2008-10-04 16:53:05.334 SetAudiotime set=1223164385.334927, audt=33856938 atc=33857053 tb=22260 sb=22260 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.337 SetAudiotime set=1223164385.337041, audt=33856940 atc=33857053 tb=21852 sb=21852 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.339 SetAudiotime set=1223164385.339182, audt=33856942 atc=33857053 tb=21444 sb=21444 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.341 SetAudiotime set=1223164385.341269, audt=33856944 atc=33857053 tb=21040 sb=21040 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.343 SetAudiotime set=1223164385.343354, audt=33856946 atc=33857053 tb=20640 sb=20640 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.345 SetAudiotime set=1223164385.345426, audt=33856948 atc=33857053 tb=20244 sb=20244 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.346 GetAudiotime now=1223164385.346641, set=1223164385.345426, ret=1, audt=33856948 sf=1 2008-10-04 16:53:05.346 NVP: A/V Divergence: -1.39507, Rate: -0.314152, Warpfactor: 1.0045, warpfactor_avg: 0.999999 2008-10-04 16:53:05.349 SetAudiotime set=1223164385.349336, audt=33856952 atc=33857053 tb=19492 sb=19492 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.350 AFD: video timecode 3047171495 3047166991 33857411 33857394 2008-10-04 16:53:05.351 SetAudiotime set=1223164385.351511, audt=33856954 atc=33857053 tb=19076 sb=19076 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.353 SetAudiotime set=1223164385.353631, audt=33856956 atc=33857053 tb=18668 sb=18668 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.355 SetAudiotime set=1223164385.355763, audt=33856958 atc=33857053 tb=18260 sb=18260 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.357 SetAudiotime set=1223164385.357849, audt=33856960 atc=33857053 tb=17860 sb=17860 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.359 SetAudiotime set=1223164385.359939, audt=33856963 atc=33857053 tb=17456 sb=17456 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.362 SetAudiotime set=1223164385.362010, audt=33856965 atc=33857053 tb=17060 sb=17060 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.363 GetAudiotime now=1223164385.363419, set=1223164385.362010, ret=1, audt=33856965 sf=1 2008-10-04 16:53:05.363 NVP: A/V Divergence: -1.6307, Rate: -0.235629, Warpfactor: 1.004, warpfactor_avg: 0.999999 2008-10-04 16:53:05.365 SetAudiotime set=1223164385.365887, audt=33856969 atc=33857053 tb=16316 sb=16316 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.367 AFD: video timecode 3047168492 3047168492 33857427 33857411 2008-10-04 16:53:05.368 SetAudiotime set=1223164385.368050, audt=33856971 atc=33857053 tb=15900 sb=15900 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.370 SetAudiotime set=1223164385.370135, audt=33856973 atc=33857053 tb=15500 sb=15500 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.372 SetAudiotime set=1223164385.372211, audt=33856975 atc=33857053 tb=15100 sb=15100 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.374 SetAudiotime set=1223164385.374286, audt=33856977 atc=33857053 tb=14704 sb=14704 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.376 SetAudiotime set=1223164385.376366, audt=33856979 atc=33857053 tb=14304 sb=14304 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.377 GetAudiotime now=1223164385.377326, set=1223164385.376366, ret=0, audt=33856979 sf=1 2008-10-04 16:53:05.377 NVP: A/V Divergence: -1.76245, Rate: -0.131751, Warpfactor: 1.0035, warpfactor_avg: 0.999999 2008-10-04 16:53:05.380 SetAudiotime set=1223164385.380146, audt=33856983 atc=33857053 tb=13576 sb=13576 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.382 SetAudiotime set=1223164385.382316, audt=33856985 atc=33857053 tb=13160 sb=13160 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.382 AFD: video timecode 3047169994 3047169994 33857444 33857427 2008-10-04 16:53:05.384 SetAudiotime set=1223164385.384472, audt=33856987 atc=33857053 tb=12748 sb=12748 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.386 SetAudiotime set=1223164385.386579, audt=33856989 atc=33857053 tb=12340 sb=12340 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.388 SetAudiotime set=1223164385.388654, audt=33856991 atc=33857053 tb=11944 sb=11944 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.390 SetAudiotime set=1223164385.390731, audt=33856993 atc=33857053 tb=11544 sb=11544 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.392 SetAudiotime set=1223164385.392843, audt=33856995 atc=33857053 tb=11140 sb=11140 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.394 GetAudiotime now=1223164385.394035, set=1223164385.392843, ret=1, audt=33856995 sf=1 2008-10-04 16:53:05.394 NVP: A/V Divergence: -1.8613, Rate: -0.0988431, Warpfactor: 1.003, warpfactor_avg: 0.999999 2008-10-04 16:53:05.397 SetAudiotime set=1223164385.397502, audt=33857000 atc=33857053 tb=10244 sb=10244 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.398 AFD: video timecode 3047176000 3047171495 33857461 0 2008-10-04 16:53:05.399 SetAudiotime set=1223164385.399652, audt=33857002 atc=33857053 tb=9832 sb=9832 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.401 SetAudiotime set=1223164385.401740, audt=33857004 atc=33857053 tb=9432 sb=9432 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.403 SetAudiotime set=1223164385.403815, audt=33857006 atc=33857053 tb=9032 sb=9032 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.405 SetAudiotime set=1223164385.405891, audt=33857009 atc=33857053 tb=8632 sb=8632 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.408 SetAudiotime set=1223164385.408189, audt=33857011 atc=33857053 tb=8192 sb=8192 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.410 SetAudiotime set=1223164385.410283, audt=33857013 atc=33857053 tb=7792 sb=7792 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.410 GetAudiotime now=1223164385.410947, set=1223164385.410283, ret=0, audt=33857013 sf=1 2008-10-04 16:53:05.411 NVP: A/V Divergence: -1.93544, Rate: -0.0741473, Warpfactor: 1.00249, warpfactor_avg: 0.999999 2008-10-04 16:53:05.413 SetAudiotime set=1223164385.413197, audt=33857016 atc=33857053 tb=7232 sb=7232 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.414 AFD: video timecode 3047172997 3047172997 33857477 33857461 2008-10-04 16:53:05.415 SetAudiotime set=1223164385.415363, audt=33857018 atc=33857053 tb=6816 sb=6816 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.417 SetAudiotime set=1223164385.417457, audt=33857020 atc=33857053 tb=6412 sb=6412 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.419 SetAudiotime set=1223164385.419542, audt=33857022 atc=33857053 tb=6012 sb=6012 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.421 SetAudiotime set=1223164385.421615, audt=33857024 atc=33857053 tb=5616 sb=5616 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.423 SetAudiotime set=1223164385.423688, audt=33857026 atc=33857053 tb=5216 sb=5216 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.425 SetAudiotime set=1223164385.425838, audt=33857028 atc=33857053 tb=4804 sb=4804 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.427 GetAudiotime now=1223164385.427391, set=1223164385.425838, ret=1, audt=33857028 sf=1 2008-10-04 16:53:05.427 NVP: A/V Divergence: -1.99101, Rate: -0.0555655, Warpfactor: 1.00199, warpfactor_avg: 0.999999 2008-10-04 16:53:05.429 SetAudiotime set=1223164385.429948, audt=33857033 atc=33857053 tb=4016 sb=4016 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.430 AFD: video timecode 3047174498 3047174498 33857494 33857477 2008-10-04 16:53:05.432 SetAudiotime set=1223164385.432124, audt=33857035 atc=33857053 tb=3596 sb=3596 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.434 SetAudiotime set=1223164385.434203, audt=33857037 atc=33857053 tb=3196 sb=3196 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.436 SetAudiotime set=1223164385.436278, audt=33857039 atc=33857053 tb=2800 sb=2800 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.438 SetAudiotime set=1223164385.438353, audt=33857041 atc=33857053 tb=2400 sb=2400 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.440 SetAudiotime set=1223164385.440509, audt=33857043 atc=33857053 tb=1988 sb=1988 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.442 SetAudiotime set=1223164385.442692, audt=33857045 atc=33857053 tb=1568 sb=1568 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.444 GetAudiotime now=1223164385.444011, set=1223164385.442692, ret=1, audt=33857045 sf=1 2008-10-04 16:53:05.444 NVP: A/V Divergence: -2.03273, Rate: -0.0417191, Warpfactor: 1.00149, warpfactor_avg: 0.999999 2008-10-04 16:53:05.446 SetAudiotime set=1223164385.446928, audt=33857050 atc=33857053 tb=752 sb=752 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.448 AFD: video timecode 3047180504 3047176000 33857511 33857494 2008-10-04 16:53:05.449 SetAudiotime set=1223164385.449090, audt=33857052 atc=33857053 tb=340 sb=340 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.451 SetAudiotime set=1223164385.451182, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.453 SetAudiotime set=1223164385.453249, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.455 SetAudiotime set=1223164385.455321, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.457 SetAudiotime set=1223164385.457391, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.459 SetAudiotime set=1223164385.459496, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.460 GetAudiotime now=1223164385.460475, set=1223164385.459496, ret=0, audt=33857053 sf=1 2008-10-04 16:53:05.460 NVP: A/V Divergence: -1.91416, Rate: 0.118564, Warpfactor: 1.00099, warpfactor_avg: 0.999999 2008-10-04 16:53:05.462 SetAudiotime set=1223164385.462511, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.462 AFD: video timecode 3047177501 3047177501 33857527 33857511 2008-10-04 16:53:05.464 SetAudiotime set=1223164385.464668, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.466 SetAudiotime set=1223164385.466736, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.468 SetAudiotime set=1223164385.468802, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.470 SetAudiotime set=1223164385.470873, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.472 SetAudiotime set=1223164385.472944, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.475 SetAudiotime set=1223164385.475016, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.477 GetAudiotime now=1223164385.477112, set=1223164385.475016, ret=2, audt=33857053 sf=1 2008-10-04 16:53:05.477 NVP: A/V Divergence: -1.61542, Rate: 0.298747, Warpfactor: 1.00049, warpfactor_avg: 0.999999 2008-10-04 16:53:05.477 SetAudiotime set=1223164385.477288, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.479 SetAudiotime set=1223164385.479534, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.479 AFD: video timecode 3047179003 3047179003 33857544 33857527 2008-10-04 16:53:05.481 SetAudiotime set=1223164385.481687, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.483 SetAudiotime set=1223164385.483763, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.485 SetAudiotime set=1223164385.485838, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.487 SetAudiotime set=1223164385.487911, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.490 SetAudiotime set=1223164385.489999, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.492 SetAudiotime set=1223164385.492085, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.493 GetAudiotime now=1223164385.493921, set=1223164385.492085, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.494 NVP: A/V Divergence: -1.12162, Rate: 0.493796, Warpfactor: 0.99999, warpfactor_avg: 0.999999 2008-10-04 16:53:05.497 SetAudiotime set=1223164385.497556, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.498 AFD: video timecode 3047185009 3047180504 33857561 33857544 2008-10-04 16:53:05.499 SetAudiotime set=1223164385.499724, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.501 SetAudiotime set=1223164385.501859, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.503 SetAudiotime set=1223164385.503956, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.506 SetAudiotime set=1223164385.506034, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.508 SetAudiotime set=1223164385.508117, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.510 SetAudiotime set=1223164385.510195, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.510 GetAudiotime now=1223164385.510477, set=1223164385.510195, ret=0, audt=33857053 sf=1 2008-10-04 16:53:05.510 NVP: A/V Divergence: -0.481568, Rate: 0.640053, Warpfactor: 1.00049, warpfactor_avg: 0.999999 2008-10-04 16:53:05.513 SetAudiotime set=1223164385.513257, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.513 AFD: video timecode 3047182006 3047182006 33857577 33857561 2008-10-04 16:53:05.515 SetAudiotime set=1223164385.515423, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.517 SetAudiotime set=1223164385.517532, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.519 SetAudiotime set=1223164385.519618, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.521 SetAudiotime set=1223164385.521687, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.523 SetAudiotime set=1223164385.523813, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.525 SetAudiotime set=1223164385.525887, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.527 GetAudiotime now=1223164385.527125, set=1223164385.525887, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.527 NVP: A/V Divergence: 0.238207, Rate: 0.719775, Warpfactor: 1.00099, warpfactor_avg: 0.999999 2008-10-04 16:53:05.529 SetAudiotime set=1223164385.529532, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.530 AFD: video timecode 3047183507 3047183507 33857594 33857577 2008-10-04 16:53:05.531 SetAudiotime set=1223164385.531685, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.533 SetAudiotime set=1223164385.533759, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.535 SetAudiotime set=1223164385.535831, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.537 SetAudiotime set=1223164385.537903, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.539 SetAudiotime set=1223164385.539974, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.542 SetAudiotime set=1223164385.542047, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.543 GetAudiotime now=1223164385.543892, set=1223164385.542047, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.544 NVP: A/V Divergence: 1.0178, Rate: 0.779596, Warpfactor: 1.00149, warpfactor_avg: 0.999999 2008-10-04 16:53:05.547 SetAudiotime set=1223164385.547561, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.547 AFD: video timecode 3047189513 3047185009 33857611 33857594 2008-10-04 16:53:05.549 SetAudiotime set=1223164385.549720, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.551 SetAudiotime set=1223164385.551804, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.553 SetAudiotime set=1223164385.553890, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.555 SetAudiotime set=1223164385.555965, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.558 SetAudiotime set=1223164385.558041, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.560 SetAudiotime set=1223164385.560147, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.562 SetAudiotime set=1223164385.562298, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.563 GetAudiotime now=1223164385.563420, set=1223164385.562298, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.563 NVP: A/V Divergence: 1.85728, Rate: 0.839477, Warpfactor: 1.00199, warpfactor_avg: 0.999999 2008-10-04 16:53:05.566 SetAudiotime set=1223164385.566598, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.566 AFD: video timecode 3047186510 3047186510 33857627 33857611 2008-10-04 16:53:05.568 SetAudiotime set=1223164385.568765, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.570 SetAudiotime set=1223164385.570851, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.572 SetAudiotime set=1223164385.572929, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.575 SetAudiotime set=1223164385.575025, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.577 SetAudiotime set=1223164385.577234, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.577 GetAudiotime now=1223164385.577416, set=1223164385.577234, ret=0, audt=33857053 sf=1 2008-10-04 16:53:05.577 NVP: A/V Divergence: 2.75658, Rate: 0.899299, Warpfactor: 1.00249, warpfactor_avg: 0.999999 2008-10-04 16:53:05.580 SetAudiotime set=1223164385.580028, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.580 AFD: video timecode 3047188012 3047188012 33857644 33857627 2008-10-04 16:53:05.582 SetAudiotime set=1223164385.582218, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.584 SetAudiotime set=1223164385.584308, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.586 SetAudiotime set=1223164385.586393, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.588 SetAudiotime set=1223164385.588473, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.590 SetAudiotime set=1223164385.590583, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.592 SetAudiotime set=1223164385.592734, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.593 GetAudiotime now=1223164385.593970, set=1223164385.592734, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.594 NVP: A/V Divergence: 3.65588, Rate: 0.899299, Warpfactor: 1.00299, warpfactor_avg: 0.999999 2008-10-04 16:53:05.598 SetAudiotime set=1223164385.598094, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.598 AFD: video timecode 3047194018 3047189513 33857661 33857644 2008-10-04 16:53:05.598 AFD: audio timecode 3047134819 3047134819 33857053 33857085 2008-10-04 16:53:05.598 GetAudiotime now=1223164385.598799, set=1223164385.598094, ret=0, audt=33857053 sf=1 2008-10-04 16:53:05.598 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33857053 needsupmix 0 2008-10-04 16:53:05.599 AFD: audio timecode 3047137699 3047137699 33857085 33857117 2008-10-04 16:53:05.599 GetAudiotime now=1223164385.599332, set=1223164385.598094, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.599 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33857085 needsupmix 0 2008-10-04 16:53:05.599 AFD: audio timecode 3047140579 3047140579 33857117 33857149 2008-10-04 16:53:05.599 GetAudiotime now=1223164385.599926, set=1223164385.598094, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.599 AO: _AddSamples samples=1536 bytes=6144, used=12289, free=1523711, timecode=33857117 needsupmix 0 2008-10-04 16:53:05.600 AFD: audio timecode 3047143459 3047143459 33857149 33857181 2008-10-04 16:53:05.600 GetAudiotime now=1223164385.600733, set=1223164385.598094, ret=2, audt=33857053 sf=1 2008-10-04 16:53:05.600 AO: _AddSamples samples=1536 bytes=6144, used=18433, free=1517567, timecode=33857149 needsupmix 0 2008-10-04 16:53:05.601 AFD: audio timecode 3047146339 3047146339 33857181 33857213 2008-10-04 16:53:05.601 GetAudiotime now=1223164385.601390, set=1223164385.598094, ret=3, audt=33857053 sf=1 2008-10-04 16:53:05.601 AO: _AddSamples samples=1536 bytes=6144, used=24577, free=1511423, timecode=33857181 needsupmix 0 2008-10-04 16:53:05.601 AFD: audio timecode 3047149219 3047149219 33857213 33857245 2008-10-04 16:53:05.601 GetAudiotime now=1223164385.601886, set=1223164385.598094, ret=3, audt=33857053 sf=1 2008-10-04 16:53:05.601 AO: _AddSamples samples=1536 bytes=6144, used=30721, free=1505279, timecode=33857213 needsupmix 0 2008-10-04 16:53:05.602 AFD: audio timecode 3047152099 3047152099 33857245 33857277 2008-10-04 16:53:05.602 GetAudiotime now=1223164385.602526, set=1223164385.598094, ret=4, audt=33857053 sf=1 2008-10-04 16:53:05.602 AO: _AddSamples samples=1536 bytes=6144, used=36865, free=1499135, timecode=33857245 needsupmix 0 2008-10-04 16:53:05.603 AFD: audio timecode 3047154979 3047154979 33857277 33857309 2008-10-04 16:53:05.603 SetAudiotime set=1223164385.603457, audt=33857053 atc=33857277 tb=43008 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.603 GetAudiotime now=1223164385.603588, set=1223164385.603457, ret=0, audt=33857053 sf=1 2008-10-04 16:53:05.603 AO: Broadcasting free space avail 2008-10-04 16:53:05.603 GetAudiotime now=1223164385.603647, set=1223164385.603457, ret=0, audt=33857053 sf=1 2008-10-04 16:53:05.603 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:05.603 AO: _AddSamples samples=1536 bytes=6144, used=36865, free=1499135, timecode=33857277 needsupmix 0 2008-10-04 16:53:05.603 WriteAudio: buffer underrun 2008-10-04 16:53:05.604 AFD: audio timecode 3047157859 3047157859 33857309 33857341 2008-10-04 16:53:05.604 GetAudiotime now=1223164385.604512, set=1223164385.603457, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.604 AO: _AddSamples samples=1536 bytes=6144, used=43009, free=1492991, timecode=33857309 needsupmix 0 2008-10-04 16:53:05.605 AFD: audio timecode 3047160739 3047160739 33857341 33857373 2008-10-04 16:53:05.605 GetAudiotime now=1223164385.605175, set=1223164385.603457, ret=1, audt=33857053 sf=1 2008-10-04 16:53:05.605 AO: _AddSamples samples=1536 bytes=6144, used=49153, free=1486847, timecode=33857341 needsupmix 0 2008-10-04 16:53:05.605 SetAudiotime set=1223164385.605290, audt=33857085 atc=33857373 tb=55296 sb=0 eds=4800000 abps=4 sf=1 2008-10-04 16:53:05.605 GetAudiotime now=1223164385.605358, set=1223164385.605290, ret=0, audt=33857085 sf=1 2008-10-04 16:53:05.605 AO: Broadcasting free space avail 2008-10-04 16:53:05.605 WriteAudio: Preparing 6144 bytes (1536 frames) 2008-10-04 16:53:05.605 SetAudiotime set=1223164385.605484, audt=33857117 atc=33857373 tb=49152 sb=0 eds=4800000 abps=4 sf=1