Ticket #5749: mythfrontend.log.t5749_enable_storing_video_pkts.diff

File mythfrontend.log.t5749_enable_storing_video_pkts.diff, 82.0 KB (added by dl-mythtv@…, 16 years ago)

audio underrun while playing 720p after applying t5749_enable_storing_video_pkts.diff

Line 
12008-10-04 16:53:04.530 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.996502, warpfactor_avg: 0.999996
22008-10-04 16:53:04.538 AFD: video timecode 3047121946 3047117441 33856860 33856843
32008-10-04 16:53:04.544 AFD: video timecode 3047118943 3047118943 33856877 33856860
42008-10-04 16:53:04.547 NVP: A/V Divergence: 0, Rate: 0, Warpfactor: 0.997, warpfactor_avg: 0.999996
52008-10-04 16:53:04.553 AFD: video timecode 3047120444 3047120444 33856893 33856877
62008-10-04 16:53:04.553 AFD: audio timecode 3047068579 3047068579 33856317 33856349
72008-10-04 16:53:04.553 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33856317 needsupmix 0
82008-10-04 16:53:04.554 AO: Broadcasting free space avail
92008-10-04 16:53:04.554 WriteAudio: Preparing 6144 bytes (1536 frames)
102008-10-04 16:53:04.554 SetAudiotime set=1223164384.554885, audt=33856157 atc=33856349 tb=36864 sb=36864 eds=4800000 abps=4 sf=1
112008-10-04 16:53:04.556 SetAudiotime set=1223164384.556956, audt=33856160 atc=33856349 tb=36476 sb=36476 eds=4800000 abps=4 sf=1
122008-10-04 16:53:04.561 SetAudiotime set=1223164384.561376, audt=33856164 atc=33856349 tb=35624 sb=35624 eds=4800000 abps=4 sf=1
132008-10-04 16:53:04.563 GetAudiotime now=1223164384.563286, set=1223164384.561376, ret=1, audt=33856164 sf=1
142008-10-04 16:53:04.563 NVP: A/V Divergence: 4.66043, Rate: 4.66043, Warpfactor: 0.997499, warpfactor_avg: 0.999996
152008-10-04 16:53:04.563 SetAudiotime set=1223164384.563564, audt=33856166 atc=33856349 tb=35204 sb=35204 eds=4800000 abps=4 sf=1
162008-10-04 16:53:04.565 SetAudiotime set=1223164384.565647, audt=33856168 atc=33856349 tb=34804 sb=34804 eds=4800000 abps=4 sf=1
172008-10-04 16:53:04.566 AFD: video timecode 3047126450 3047121946 33856910 33856893
182008-10-04 16:53:04.566 AFD: audio timecode 3047071459 3047071459 33856349 33856381
192008-10-04 16:53:04.566 GetAudiotime now=1223164384.566756, set=1223164384.565647, ret=1, audt=33856168 sf=1
202008-10-04 16:53:04.566 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33856349 needsupmix 0
212008-10-04 16:53:04.567 AFD: audio timecode 3047074339 3047074339 33856381 33856413
222008-10-04 16:53:04.567 GetAudiotime now=1223164384.567285, set=1223164384.565647, ret=1, audt=33856168 sf=1
232008-10-04 16:53:04.567 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856381 needsupmix 0
242008-10-04 16:53:04.567 SetAudiotime set=1223164384.567799, audt=33856170 atc=33856413 tb=46680 sb=34392 eds=4800000 abps=4 sf=1
252008-10-04 16:53:04.567 GetAudiotime now=1223164384.567928, set=1223164384.567799, ret=0, audt=33856170 sf=1
262008-10-04 16:53:04.567 AFD: audio timecode 3047077219 3047077219 33856413 33856445
272008-10-04 16:53:04.567 AO: Broadcasting free space avail
282008-10-04 16:53:04.568 WriteAudio: Preparing 6144 bytes (1536 frames)
292008-10-04 16:53:04.568 GetAudiotime now=1223164384.568042, set=1223164384.567799, ret=0, audt=33856170 sf=1
302008-10-04 16:53:04.568 SetAudiotime set=1223164384.568164, audt=33856171 atc=33856413 tb=46612 sb=40468 eds=4800000 abps=4 sf=1
312008-10-04 16:53:04.568 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856413 needsupmix 0
322008-10-04 16:53:04.568 GetAudiotime now=1223164384.568231, set=1223164384.568164, ret=0, audt=33856171 sf=1
332008-10-04 16:53:04.568 AO: Broadcasting free space avail
342008-10-04 16:53:04.568 WriteAudio: Preparing 6144 bytes (1536 frames)
352008-10-04 16:53:04.568 SetAudiotime set=1223164384.568378, audt=33856171 atc=33856445 tb=52712 sb=46568 eds=4800000 abps=4 sf=1
362008-10-04 16:53:04.568 GetAudiotime now=1223164384.568432, set=1223164384.568378, ret=0, audt=33856171 sf=1
372008-10-04 16:53:04.568 AO: Broadcasting free space avail
382008-10-04 16:53:04.568 WriteAudio: Preparing 6144 bytes (1536 frames)
392008-10-04 16:53:04.568 SetAudiotime set=1223164384.568557, audt=33856171 atc=33856445 tb=52680 sb=52680 eds=4800000 abps=4 sf=1
402008-10-04 16:53:04.568 AFD: audio timecode 3047080099 3047080099 33856445 33856477
412008-10-04 16:53:04.568 GetAudiotime now=1223164384.568955, set=1223164384.568557, ret=0, audt=33856171 sf=1
422008-10-04 16:53:04.569 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33856445 needsupmix 0
432008-10-04 16:53:04.569 AFD: audio timecode 3047082979 3047082979 33856477 33856509
442008-10-04 16:53:04.569 GetAudiotime now=1223164384.569679, set=1223164384.568557, ret=1, audt=33856171 sf=1
452008-10-04 16:53:04.569 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856477 needsupmix 0
462008-10-04 16:53:04.570 AFD: audio timecode 3047085859 3047085859 33856509 33856541
472008-10-04 16:53:04.570 GetAudiotime now=1223164384.570414, set=1223164384.568557, ret=1, audt=33856171 sf=1
482008-10-04 16:53:04.570 AO: _AddSamples samples=1536 bytes=6144, used=12289, free=1523711, timecode=33856509 needsupmix 0
492008-10-04 16:53:04.570 SetAudiotime set=1223164384.570625, audt=33856173 atc=33856541 tb=70712 sb=52280 eds=4800000 abps=4 sf=1
502008-10-04 16:53:04.570 GetAudiotime now=1223164384.570688, set=1223164384.570625, ret=0, audt=33856173 sf=1
512008-10-04 16:53:04.570 AO: Broadcasting free space avail
522008-10-04 16:53:04.570 WriteAudio: Preparing 6144 bytes (1536 frames)
532008-10-04 16:53:04.570 SetAudiotime set=1223164384.570821, audt=33856173 atc=33856541 tb=70676 sb=58388 eds=4800000 abps=4 sf=1
542008-10-04 16:53:04.570 GetAudiotime now=1223164384.570873, set=1223164384.570821, ret=0, audt=33856173 sf=1
552008-10-04 16:53:04.570 AO: Broadcasting free space avail
562008-10-04 16:53:04.570 WriteAudio: Preparing 6144 bytes (1536 frames)
572008-10-04 16:53:04.570 SetAudiotime set=1223164384.570994, audt=33856174 atc=33856541 tb=70644 sb=64500 eds=4800000 abps=4 sf=1
582008-10-04 16:53:04.571 AFD: audio timecode 3047088739 3047088739 33856541 33856573
592008-10-04 16:53:04.571 AO: audio waiting for space on soundcard: have 1036 need 6144
602008-10-04 16:53:04.571 GetAudiotime now=1223164384.571076, set=1223164384.570994, ret=0, audt=33856174 sf=1
612008-10-04 16:53:04.571 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33856541 needsupmix 0
622008-10-04 16:53:04.571 AFD: audio timecode 3047091619 3047091619 33856573 33856605
632008-10-04 16:53:04.571 GetAudiotime now=1223164384.571472, set=1223164384.570994, ret=0, audt=33856174 sf=1
642008-10-04 16:53:04.571 AO: _AddSamples samples=1536 bytes=6144, used=12289, free=1523711, timecode=33856573 needsupmix 0
652008-10-04 16:53:04.572 AFD: audio timecode 3047094499 3047094499 33856605 33856637
662008-10-04 16:53:04.572 GetAudiotime now=1223164384.572248, set=1223164384.570994, ret=1, audt=33856174 sf=1
672008-10-04 16:53:04.572 AO: _AddSamples samples=1536 bytes=6144, used=18433, free=1517567, timecode=33856605 needsupmix 0
682008-10-04 16:53:04.572 AFD: audio timecode 3047097379 3047097379 33856637 33856669
692008-10-04 16:53:04.572 GetAudiotime now=1223164384.572798, set=1223164384.570994, ret=1, audt=33856174 sf=1
702008-10-04 16:53:04.572 AO: _AddSamples samples=1536 bytes=6144, used=24577, free=1511423, timecode=33856637 needsupmix 0
712008-10-04 16:53:04.573 AFD: audio timecode 3047100259 3047100259 33856669 33856701
722008-10-04 16:53:04.573 GetAudiotime now=1223164384.573435, set=1223164384.570994, ret=2, audt=33856174 sf=1
732008-10-04 16:53:04.573 AO: _AddSamples samples=1536 bytes=6144, used=30721, free=1505279, timecode=33856669 needsupmix 0
742008-10-04 16:53:04.574 AFD: audio timecode 3047103139 3047103139 33856701 33856733
752008-10-04 16:53:04.574 GetAudiotime now=1223164384.574282, set=1223164384.570994, ret=3, audt=33856174 sf=1
762008-10-04 16:53:04.574 AO: _AddSamples samples=1536 bytes=6144, used=36865, free=1499135, timecode=33856701 needsupmix 0
772008-10-04 16:53:04.574 AFD: audio timecode 3047106019 3047106019 33856733 33856765
782008-10-04 16:53:04.574 GetAudiotime now=1223164384.574960, set=1223164384.570994, ret=3, audt=33856174 sf=1
792008-10-04 16:53:04.575 AO: _AddSamples samples=1536 bytes=6144, used=43009, free=1492991, timecode=33856733 needsupmix 0
802008-10-04 16:53:04.575 AFD: audio timecode 3047108899 3047108899 33856765 33856797
812008-10-04 16:53:04.575 GetAudiotime now=1223164384.575716, set=1223164384.570994, ret=4, audt=33856174 sf=1
822008-10-04 16:53:04.575 AO: _AddSamples samples=1536 bytes=6144, used=49153, free=1486847, timecode=33856765 needsupmix 0
832008-10-04 16:53:04.576 AFD: audio timecode 3047111779 3047111779 33856797 33856829
842008-10-04 16:53:04.576 GetAudiotime now=1223164384.576450, set=1223164384.570994, ret=5, audt=33856174 sf=1
852008-10-04 16:53:04.576 AO: _AddSamples samples=1536 bytes=6144, used=55297, free=1480703, timecode=33856797 needsupmix 0
862008-10-04 16:53:04.577 AFD: audio timecode 3047114659 3047114659 33856829 33856861
872008-10-04 16:53:04.577 GetAudiotime now=1223164384.577076, set=1223164384.570994, ret=6, audt=33856174 sf=1
882008-10-04 16:53:04.577 AO: _AddSamples samples=1536 bytes=6144, used=61441, free=1474559, timecode=33856829 needsupmix 0
892008-10-04 16:53:04.577 AFD: audio timecode 3047117539 3047117539 33856861 33856893
902008-10-04 16:53:04.577 GetAudiotime now=1223164384.577707, set=1223164384.570994, ret=6, audt=33856174 sf=1
912008-10-04 16:53:04.577 AO: _AddSamples samples=1536 bytes=6144, used=67585, free=1468415, timecode=33856861 needsupmix 0
922008-10-04 16:53:04.578 AFD: audio timecode 3047120419 3047120419 33856893 33856925
932008-10-04 16:53:04.578 SetAudiotime set=1223164384.578127, audt=33856181 atc=33856893 tb=136856 sb=63128 eds=4800000 abps=4 sf=1
942008-10-04 16:53:04.578 AO: audio waiting for space on soundcard: have 2408 need 6144
952008-10-04 16:53:04.580 NVP: Video is 4.66043 frames ahead of audio,
96                        doubling video frame interval to slow down.
972008-10-04 16:53:04.580 GetAudiotime now=1223164384.580098, set=1223164384.578127, ret=1, audt=33856181 sf=1
982008-10-04 16:53:04.580 AO: _AddSamples samples=1536 bytes=6144, used=73729, free=1462271, timecode=33856893 needsupmix 0
992008-10-04 16:53:04.580 GetAudiotime now=1223164384.580223, set=1223164384.578127, ret=2, audt=33856181 sf=1
1002008-10-04 16:53:04.580 NVP: A/V Divergence: 8.14074, Rate: 3.48031, Warpfactor: 0.997997, warpfactor_avg: 0.999996
1012008-10-04 16:53:04.580 AFD: audio timecode 3047123299 3047123299 33856925 33856957
1022008-10-04 16:53:04.580 GetAudiotime now=1223164384.580860, set=1223164384.578127, ret=2, audt=33856181 sf=1
1032008-10-04 16:53:04.580 AO: _AddSamples samples=1536 bytes=6144, used=79873, free=1456127, timecode=33856925 needsupmix 0
1042008-10-04 16:53:04.581 AFD: audio timecode 3047126179 3047126179 33856957 33856989
1052008-10-04 16:53:04.581 GetAudiotime now=1223164384.581596, set=1223164384.578127, ret=3, audt=33856181 sf=1
1062008-10-04 16:53:04.581 AO: _AddSamples samples=1536 bytes=6144, used=86017, free=1449983, timecode=33856957 needsupmix 0
1072008-10-04 16:53:04.582 AFD: audio timecode 3047129059 3047129059 33856989 33857021
1082008-10-04 16:53:04.582 GetAudiotime now=1223164384.582304, set=1223164384.578127, ret=4, audt=33856181 sf=1
1092008-10-04 16:53:04.582 AO: _AddSamples samples=1536 bytes=6144, used=92161, free=1443839, timecode=33856989 needsupmix 0
1102008-10-04 16:53:04.583 SetAudiotime set=1223164384.583278, audt=33856186 atc=33857021 tb=160444 sb=62140 eds=4800000 abps=4 sf=1
1112008-10-04 16:53:04.583 AO: audio waiting for space on soundcard: have 3396 need 6144
1122008-10-04 16:53:04.587 AFD: video timecode 3047123447 3047123447 33856927 33856910
1132008-10-04 16:53:04.588 AFD: audio timecode 3047131939 3047131939 33857021 33857053
1142008-10-04 16:53:04.588 GetAudiotime now=1223164384.588124, set=1223164384.583278, ret=4, audt=33856186 sf=1
1152008-10-04 16:53:04.588 AO: _AddSamples samples=1536 bytes=6144, used=98305, free=1437695, timecode=33857021 needsupmix 0
1162008-10-04 16:53:04.588 SetAudiotime set=1223164384.588378, audt=33856191 atc=33857053 tb=165608 sb=61160 eds=4800000 abps=4 sf=1
1172008-10-04 16:53:04.588 AO: audio waiting for space on soundcard: have 4376 need 6144
1182008-10-04 16:53:04.593 AFD: video timecode 3047124949 3047124949 33856943 33856927
1192008-10-04 16:53:04.593 SetAudiotime set=1223164384.593478, audt=33856196 atc=33857053 tb=164628 sb=60180 eds=4800000 abps=4 sf=1
1202008-10-04 16:53:04.593 AO: audio waiting for space on soundcard: have 5356 need 6144
1212008-10-04 16:53:04.598 SetAudiotime set=1223164384.598877, audt=33856201 atc=33857053 tb=163592 sb=59144 eds=4800000 abps=4 sf=1
1222008-10-04 16:53:04.599 GetAudiotime now=1223164384.599002, set=1223164384.598877, ret=0, audt=33856201 sf=1
1232008-10-04 16:53:04.599 AO: Broadcasting free space avail
1242008-10-04 16:53:04.599 WriteAudio: Preparing 6144 bytes (1536 frames)
1252008-10-04 16:53:04.599 SetAudiotime set=1223164384.599143, audt=33856202 atc=33857053 tb=163540 sb=65236 eds=4800000 abps=4 sf=1
1262008-10-04 16:53:04.599 AO: audio waiting for space on soundcard: have 300 need 6144
1272008-10-04 16:53:04.601 AFD: video timecode 3047130955 3047126450 33856960 0
1282008-10-04 16:53:04.604 SetAudiotime set=1223164384.604247, audt=33856207 atc=33857053 tb=162560 sb=64256 eds=4800000 abps=4 sf=1
1292008-10-04 16:53:04.604 AO: audio waiting for space on soundcard: have 1276 need 6144
1302008-10-04 16:53:04.608 AFD: video timecode 3047127952 3047127952 33856977 33856960
1312008-10-04 16:53:04.611 SetAudiotime set=1223164384.611423, audt=33856214 atc=33857053 tb=161184 sb=62880 eds=4800000 abps=4 sf=1
1322008-10-04 16:53:04.611 AO: audio waiting for space on soundcard: have 2656 need 6144
1332008-10-04 16:53:04.614 NVP: Video is 8.14074 frames ahead of audio,
134                        doubling video frame interval to slow down.
1352008-10-04 16:53:04.614 GetAudiotime now=1223164384.614261, set=1223164384.611423, ret=2, audt=33856214 sf=1
1362008-10-04 16:53:04.614 NVP: A/V Divergence: 10.5112, Rate: 2.3705, Warpfactor: 0.998496, warpfactor_avg: 0.999996
1372008-10-04 16:53:04.616 SetAudiotime set=1223164384.616532, audt=33856219 atc=33857053 tb=160204 sb=61900 eds=4800000 abps=4 sf=1
1382008-10-04 16:53:04.616 AO: audio waiting for space on soundcard: have 3636 need 6144
1392008-10-04 16:53:04.621 SetAudiotime set=1223164384.621652, audt=33856224 atc=33857053 tb=159220 sb=60916 eds=4800000 abps=4 sf=1
1402008-10-04 16:53:04.621 AO: audio waiting for space on soundcard: have 4620 need 6144
1412008-10-04 16:53:04.622 AFD: video timecode 3047129453 3047129453 33856993 33856977
1422008-10-04 16:53:04.626 SetAudiotime set=1223164384.626867, audt=33856229 atc=33857053 tb=158220 sb=59916 eds=4800000 abps=4 sf=1
1432008-10-04 16:53:04.626 AO: audio waiting for space on soundcard: have 5620 need 6144
1442008-10-04 16:53:04.629 AFD: video timecode 3047135459 3047130955 33857010 33856993
1452008-10-04 16:53:04.632 SetAudiotime set=1223164384.632067, audt=33856235 atc=33857053 tb=157220 sb=58916 eds=4800000 abps=4 sf=1
1462008-10-04 16:53:04.632 GetAudiotime now=1223164384.632182, set=1223164384.632067, ret=0, audt=33856235 sf=1
1472008-10-04 16:53:04.632 AO: Broadcasting free space avail
1482008-10-04 16:53:04.632 WriteAudio: Preparing 6144 bytes (1536 frames)
1492008-10-04 16:53:04.632 SetAudiotime set=1223164384.632319, audt=33856235 atc=33857053 tb=157172 sb=65012 eds=4800000 abps=4 sf=1
1502008-10-04 16:53:04.632 AO: audio waiting for space on soundcard: have 524 need 6144
1512008-10-04 16:53:04.637 SetAudiotime set=1223164384.637427, audt=33856240 atc=33857053 tb=156192 sb=64032 eds=4800000 abps=4 sf=1
1522008-10-04 16:53:04.637 AO: audio waiting for space on soundcard: have 1504 need 6144
1532008-10-04 16:53:04.644 SetAudiotime set=1223164384.644793, audt=33856247 atc=33857053 tb=154776 sb=62616 eds=4800000 abps=4 sf=1
1542008-10-04 16:53:04.644 AO: audio waiting for space on soundcard: have 2920 need 6144
1552008-10-04 16:53:04.646 NVP: Video is 10.5112 frames ahead of audio,
156                        doubling video frame interval to slow down.
1572008-10-04 16:53:04.646 GetAudiotime now=1223164384.646558, set=1223164384.644793, ret=1, audt=33856247 sf=1
1582008-10-04 16:53:04.646 NVP: A/V Divergence: 12.0493, Rate: 1.53809, Warpfactor: 0.998995, warpfactor_avg: 0.999996
1592008-10-04 16:53:04.649 SetAudiotime set=1223164384.649923, audt=33856252 atc=33857053 tb=153792 sb=61632 eds=4800000 abps=4 sf=1
1602008-10-04 16:53:04.649 AO: audio waiting for space on soundcard: have 3904 need 6144
1612008-10-04 16:53:04.650 AFD: video timecode 3047132456 3047132456 33857027 33857010
1622008-10-04 16:53:04.655 SetAudiotime set=1223164384.655041, audt=33856258 atc=33857053 tb=152808 sb=60648 eds=4800000 abps=4 sf=1
1632008-10-04 16:53:04.655 AO: audio waiting for space on soundcard: have 4888 need 6144
1642008-10-04 16:53:04.660 SetAudiotime set=1223164384.660170, audt=33856263 atc=33857053 tb=151824 sb=59664 eds=4800000 abps=4 sf=1
1652008-10-04 16:53:04.660 AO: audio waiting for space on soundcard: have 5872 need 6144
1662008-10-04 16:53:04.665 SetAudiotime set=1223164384.665350, audt=33856268 atc=33857053 tb=150828 sb=58668 eds=4800000 abps=4 sf=1
1672008-10-04 16:53:04.665 GetAudiotime now=1223164384.665416, set=1223164384.665350, ret=0, audt=33856268 sf=1
1682008-10-04 16:53:04.665 AO: Broadcasting free space avail
1692008-10-04 16:53:04.665 WriteAudio: Preparing 6144 bytes (1536 frames)
1702008-10-04 16:53:04.665 SetAudiotime set=1223164384.665550, audt=33856268 atc=33857053 tb=150792 sb=64776 eds=4800000 abps=4 sf=1
1712008-10-04 16:53:04.665 AO: audio waiting for space on soundcard: have 760 need 6144
1722008-10-04 16:53:04.670 SetAudiotime set=1223164384.670666, audt=33856273 atc=33857053 tb=149808 sb=63792 eds=4800000 abps=4 sf=1
1732008-10-04 16:53:04.670 AO: audio waiting for space on soundcard: have 1744 need 6144
1742008-10-04 16:53:04.678 SetAudiotime set=1223164384.678161, audt=33856281 atc=33857053 tb=148368 sb=62352 eds=4800000 abps=4 sf=1
1752008-10-04 16:53:04.678 AO: audio waiting for space on soundcard: have 3184 need 6144
1762008-10-04 16:53:04.679 NVP: Video is 12.0493 frames ahead of audio,
177                        doubling video frame interval to slow down.
1782008-10-04 16:53:04.679 GetAudiotime now=1223164384.679949, set=1223164384.678161, ret=1, audt=33856281 sf=1
1792008-10-04 16:53:04.680 NVP: A/V Divergence: 12.9482, Rate: 0.898819, Warpfactor: 0.999495, warpfactor_avg: 0.999996
1802008-10-04 16:53:04.683 SetAudiotime set=1223164384.683297, audt=33856286 atc=33857053 tb=147384 sb=61368 eds=4800000 abps=4 sf=1
1812008-10-04 16:53:04.683 AO: audio waiting for space on soundcard: have 4168 need 6144
1822008-10-04 16:53:04.684 AFD: video timecode 3047133958 3047133958 33857043 33857027
1832008-10-04 16:53:04.688 SetAudiotime set=1223164384.688454, audt=33856291 atc=33857053 tb=146392 sb=60376 eds=4800000 abps=4 sf=1
1842008-10-04 16:53:04.688 AO: audio waiting for space on soundcard: have 5160 need 6144
1852008-10-04 16:53:04.693 SetAudiotime set=1223164384.693632, audt=33856296 atc=33857053 tb=145400 sb=59384 eds=4800000 abps=4 sf=1
1862008-10-04 16:53:04.693 GetAudiotime now=1223164384.693692, set=1223164384.693632, ret=0, audt=33856296 sf=1
1872008-10-04 16:53:04.693 AO: Broadcasting free space avail
1882008-10-04 16:53:04.693 WriteAudio: Preparing 6144 bytes (1536 frames)
1892008-10-04 16:53:04.693 SetAudiotime set=1223164384.693838, audt=33856296 atc=33857053 tb=145360 sb=65488 eds=4800000 abps=4 sf=1
1902008-10-04 16:53:04.693 AO: audio waiting for space on soundcard: have 48 need 6144
1912008-10-04 16:53:04.698 SetAudiotime set=1223164384.698942, audt=33856302 atc=33857053 tb=144380 sb=64508 eds=4800000 abps=4 sf=1
1922008-10-04 16:53:04.699 AO: audio waiting for space on soundcard: have 1028 need 6144
1932008-10-04 16:53:04.704 SetAudiotime set=1223164384.704054, audt=33856307 atc=33857053 tb=143396 sb=63524 eds=4800000 abps=4 sf=1
1942008-10-04 16:53:04.704 AO: audio waiting for space on soundcard: have 2012 need 6144
1952008-10-04 16:53:04.711 SetAudiotime set=1223164384.711525, audt=33856314 atc=33857053 tb=141964 sb=62092 eds=4800000 abps=4 sf=1
1962008-10-04 16:53:04.711 AO: audio waiting for space on soundcard: have 3444 need 6144
1972008-10-04 16:53:04.712 NVP: Video is 12.9482 frames ahead of audio,
198                        doubling video frame interval to slow down.
1992008-10-04 16:53:04.712 GetAudiotime now=1223164384.712824, set=1223164384.711525, ret=1, audt=33856314 sf=1
2002008-10-04 16:53:04.712 NVP: A/V Divergence: 13.3825, Rate: 0.434334, Warpfactor: 0.999995, warpfactor_avg: 0.999996
2012008-10-04 16:53:04.716 SetAudiotime set=1223164384.716651, audt=33856319 atc=33857053 tb=140980 sb=61108 eds=4800000 abps=4 sf=1
2022008-10-04 16:53:04.716 AO: audio waiting for space on soundcard: have 4428 need 6144
2032008-10-04 16:53:04.720 AFD: video timecode 3047139964 3047135459 33857060 33857043
2042008-10-04 16:53:04.721 SetAudiotime set=1223164384.721850, audt=33856324 atc=33857053 tb=139980 sb=60108 eds=4800000 abps=4 sf=1
2052008-10-04 16:53:04.721 AO: audio waiting for space on soundcard: have 5428 need 6144
2062008-10-04 16:53:04.726 SetAudiotime set=1223164384.726981, audt=33856330 atc=33857053 tb=138996 sb=59124 eds=4800000 abps=4 sf=1
2072008-10-04 16:53:04.727 GetAudiotime now=1223164384.727043, set=1223164384.726981, ret=0, audt=33856330 sf=1
2082008-10-04 16:53:04.727 AO: Broadcasting free space avail
2092008-10-04 16:53:04.727 WriteAudio: Preparing 6144 bytes (1536 frames)
2102008-10-04 16:53:04.727 SetAudiotime set=1223164384.727179, audt=33856330 atc=33857053 tb=138956 sb=65228 eds=4800000 abps=4 sf=1
2112008-10-04 16:53:04.727 AO: audio waiting for space on soundcard: have 308 need 6144
2122008-10-04 16:53:04.732 SetAudiotime set=1223164384.732299, audt=33856335 atc=33857053 tb=137972 sb=64244 eds=4800000 abps=4 sf=1
2132008-10-04 16:53:04.732 AO: audio waiting for space on soundcard: have 1288 need 6144
2142008-10-04 16:53:04.737 SetAudiotime set=1223164384.737479, audt=33856340 atc=33857053 tb=136980 sb=63252 eds=4800000 abps=4 sf=1
2152008-10-04 16:53:04.737 AO: audio waiting for space on soundcard: have 2284 need 6144
2162008-10-04 16:53:04.742 SetAudiotime set=1223164384.742880, audt=33856345 atc=33857053 tb=135944 sb=62216 eds=4800000 abps=4 sf=1
2172008-10-04 16:53:04.742 AO: audio waiting for space on soundcard: have 3320 need 6144
2182008-10-04 16:53:04.746 NVP: Video is 13.3825 frames ahead of audio,
219                        doubling video frame interval to slow down.
2202008-10-04 16:53:04.746 GetAudiotime now=1223164384.746695, set=1223164384.742880, ret=3, audt=33856345 sf=1
2212008-10-04 16:53:04.746 NVP: A/V Divergence: 13.4535, Rate: 0.0710304, Warpfactor: 1.00049, warpfactor_avg: 0.999996
2222008-10-04 16:53:04.749 SetAudiotime set=1223164384.749279, audt=33856352 atc=33857053 tb=134712 sb=60984 eds=4800000 abps=4 sf=1
2232008-10-04 16:53:04.749 AO: audio waiting for space on soundcard: have 4548 need 6144
2242008-10-04 16:53:04.754 SetAudiotime set=1223164384.754486, audt=33856357 atc=33857053 tb=133712 sb=59984 eds=4800000 abps=4 sf=1
2252008-10-04 16:53:04.754 AO: audio waiting for space on soundcard: have 5548 need 6144
2262008-10-04 16:53:04.754 AFD: video timecode 3047136961 3047136961 33857077 33857060
2272008-10-04 16:53:04.759 SetAudiotime set=1223164384.759681, audt=33856362 atc=33857053 tb=132716 sb=58988 eds=4800000 abps=4 sf=1
2282008-10-04 16:53:04.759 GetAudiotime now=1223164384.759745, set=1223164384.759681, ret=0, audt=33856362 sf=1
2292008-10-04 16:53:04.759 AO: Broadcasting free space avail
2302008-10-04 16:53:04.759 WriteAudio: Preparing 6144 bytes (1536 frames)
2312008-10-04 16:53:04.759 SetAudiotime set=1223164384.759889, audt=33856362 atc=33857053 tb=132676 sb=65092 eds=4800000 abps=4 sf=1
2322008-10-04 16:53:04.759 AO: audio waiting for space on soundcard: have 444 need 6144
2332008-10-04 16:53:04.764 SetAudiotime set=1223164384.764992, audt=33856368 atc=33857053 tb=131696 sb=64112 eds=4800000 abps=4 sf=1
2342008-10-04 16:53:04.765 AO: audio waiting for space on soundcard: have 1424 need 6144
2352008-10-04 16:53:04.770 SetAudiotime set=1223164384.770119, audt=33856373 atc=33857053 tb=130712 sb=63128 eds=4800000 abps=4 sf=1
2362008-10-04 16:53:04.770 AO: audio waiting for space on soundcard: have 2408 need 6144
2372008-10-04 16:53:04.775 SetAudiotime set=1223164384.775228, audt=33856378 atc=33857053 tb=129732 sb=62148 eds=4800000 abps=4 sf=1
2382008-10-04 16:53:04.775 AO: audio waiting for space on soundcard: have 3388 need 6144
2392008-10-04 16:53:04.779 NVP: Video is 13.4535 frames ahead of audio,
240                        doubling video frame interval to slow down.
2412008-10-04 16:53:04.779 GetAudiotime now=1223164384.779924, set=1223164384.775228, ret=4, audt=33856378 sf=1
2422008-10-04 16:53:04.780 NVP: A/V Divergence: 13.252, Rate: -0.201523, Warpfactor: 1.00099, warpfactor_avg: 0.999996
2432008-10-04 16:53:04.782 SetAudiotime set=1223164384.782263, audt=33856385 atc=33857053 tb=128380 sb=60796 eds=4800000 abps=4 sf=1
2442008-10-04 16:53:04.782 AO: audio waiting for space on soundcard: have 4740 need 6144
2452008-10-04 16:53:04.785 AFD: video timecode 3047138462 3047138462 33857094 33857077
2462008-10-04 16:53:04.787 SetAudiotime set=1223164384.787462, audt=33856390 atc=33857053 tb=127380 sb=59796 eds=4800000 abps=4 sf=1
2472008-10-04 16:53:04.787 AO: audio waiting for space on soundcard: have 5736 need 6144
2482008-10-04 16:53:04.792 SetAudiotime set=1223164384.792589, audt=33856395 atc=33857053 tb=126396 sb=58812 eds=4800000 abps=4 sf=1
2492008-10-04 16:53:04.792 GetAudiotime now=1223164384.792653, set=1223164384.792589, ret=0, audt=33856395 sf=1
2502008-10-04 16:53:04.792 AO: Broadcasting free space avail
2512008-10-04 16:53:04.792 WriteAudio: Preparing 6144 bytes (1536 frames)
2522008-10-04 16:53:04.792 SetAudiotime set=1223164384.792796, audt=33856395 atc=33857053 tb=126356 sb=64916 eds=4800000 abps=4 sf=1
2532008-10-04 16:53:04.792 AO: audio waiting for space on soundcard: have 620 need 6144
2542008-10-04 16:53:04.797 SetAudiotime set=1223164384.797899, audt=33856400 atc=33857053 tb=125376 sb=63936 eds=4800000 abps=4 sf=1
2552008-10-04 16:53:04.797 AO: audio waiting for space on soundcard: have 1600 need 6144
2562008-10-04 16:53:04.803 SetAudiotime set=1223164384.803008, audt=33856406 atc=33857053 tb=124396 sb=62956 eds=4800000 abps=4 sf=1
2572008-10-04 16:53:04.803 AO: audio waiting for space on soundcard: have 2580 need 6144
2582008-10-04 16:53:04.808 SetAudiotime set=1223164384.808121, audt=33856411 atc=33857053 tb=123416 sb=61976 eds=4800000 abps=4 sf=1
2592008-10-04 16:53:04.808 AO: audio waiting for space on soundcard: have 3560 need 6144
2602008-10-04 16:53:04.813 SetAudiotime set=1223164384.813227, audt=33856416 atc=33857053 tb=122436 sb=60996 eds=4800000 abps=4 sf=1
2612008-10-04 16:53:04.813 AO: audio waiting for space on soundcard: have 4540 need 6144
2622008-10-04 16:53:04.813 NVP: Video is 13.252 frames ahead of audio,
263                        doubling video frame interval to slow down.
2642008-10-04 16:53:04.813 GetAudiotime now=1223164384.813801, set=1223164384.813227, ret=0, audt=33856416 sf=1
2652008-10-04 16:53:04.813 NVP: A/V Divergence: 12.8461, Rate: -0.405862, Warpfactor: 1.0015, warpfactor_avg: 0.999996
2662008-10-04 16:53:04.818 SetAudiotime set=1223164384.818350, audt=33856421 atc=33857053 tb=121452 sb=60012 eds=4800000 abps=4 sf=1
2672008-10-04 16:53:04.818 AO: audio waiting for space on soundcard: have 5524 need 6144
2682008-10-04 16:53:04.821 AFD: video timecode 3047144468 3047139964 33857110 33857094
2692008-10-04 16:53:04.823 SetAudiotime set=1223164384.823544, audt=33856426 atc=33857053 tb=120452 sb=59012 eds=4800000 abps=4 sf=1
2702008-10-04 16:53:04.823 GetAudiotime now=1223164384.823621, set=1223164384.823544, ret=0, audt=33856426 sf=1
2712008-10-04 16:53:04.823 AO: Broadcasting free space avail
2722008-10-04 16:53:04.823 WriteAudio: Preparing 6144 bytes (1536 frames)
2732008-10-04 16:53:04.823 SetAudiotime set=1223164384.823762, audt=33856426 atc=33857053 tb=120412 sb=65116 eds=4800000 abps=4 sf=1
2742008-10-04 16:53:04.823 AO: audio waiting for space on soundcard: have 420 need 6144
2752008-10-04 16:53:04.828 SetAudiotime set=1223164384.828943, audt=33856432 atc=33857053 tb=119416 sb=64120 eds=4800000 abps=4 sf=1
2762008-10-04 16:53:04.829 AO: audio waiting for space on soundcard: have 1416 need 6144
2772008-10-04 16:53:04.834 SetAudiotime set=1223164384.834063, audt=33856437 atc=33857053 tb=118436 sb=63140 eds=4800000 abps=4 sf=1
2782008-10-04 16:53:04.834 AO: audio waiting for space on soundcard: have 2396 need 6144
2792008-10-04 16:53:04.839 SetAudiotime set=1223164384.839174, audt=33856442 atc=33857053 tb=117452 sb=62156 eds=4800000 abps=4 sf=1
2802008-10-04 16:53:04.839 AO: audio waiting for space on soundcard: have 3380 need 6144
2812008-10-04 16:53:04.844 SetAudiotime set=1223164384.844282, audt=33856447 atc=33857053 tb=116472 sb=61176 eds=4800000 abps=4 sf=1
2822008-10-04 16:53:04.844 AO: audio waiting for space on soundcard: have 4360 need 6144
2832008-10-04 16:53:04.846 NVP: Video is 12.8461 frames ahead of audio,
284                        doubling video frame interval to slow down.
2852008-10-04 16:53:04.846 GetAudiotime now=1223164384.846553, set=1223164384.844282, ret=2, audt=33856447 sf=1
2862008-10-04 16:53:04.846 NVP: A/V Divergence: 12.287, Rate: -0.559132, Warpfactor: 1.002, warpfactor_avg: 0.999996
2872008-10-04 16:53:04.849 SetAudiotime set=1223164384.849409, audt=33856452 atc=33857053 tb=115488 sb=60192 eds=4800000 abps=4 sf=1
2882008-10-04 16:53:04.849 AO: audio waiting for space on soundcard: have 5344 need 6144
2892008-10-04 16:53:04.854 SetAudiotime set=1223164384.854599, audt=33856457 atc=33857053 tb=114492 sb=59196 eds=4800000 abps=4 sf=1
2902008-10-04 16:53:04.854 GetAudiotime now=1223164384.854729, set=1223164384.854599, ret=0, audt=33856457 sf=1
2912008-10-04 16:53:04.854 AO: Broadcasting free space avail
2922008-10-04 16:53:04.854 WriteAudio: Preparing 6144 bytes (1536 frames)
2932008-10-04 16:53:04.854 SetAudiotime set=1223164384.854867, audt=33856457 atc=33857053 tb=114440 sb=65288 eds=4800000 abps=4 sf=1
2942008-10-04 16:53:04.854 AO: audio waiting for space on soundcard: have 248 need 6144
2952008-10-04 16:53:04.855 AFD: video timecode 3047141465 3047141465 33857127 33857110
2962008-10-04 16:53:04.859 SetAudiotime set=1223164384.859982, audt=33856463 atc=33857053 tb=113456 sb=64304 eds=4800000 abps=4 sf=1
2972008-10-04 16:53:04.860 AO: audio waiting for space on soundcard: have 1232 need 6144
2982008-10-04 16:53:04.865 SetAudiotime set=1223164384.865131, audt=33856468 atc=33857053 tb=112468 sb=63316 eds=4800000 abps=4 sf=1
2992008-10-04 16:53:04.865 AO: audio waiting for space on soundcard: have 2220 need 6144
3002008-10-04 16:53:04.870 SetAudiotime set=1223164384.870265, audt=33856473 atc=33857053 tb=111480 sb=62328 eds=4800000 abps=4 sf=1
3012008-10-04 16:53:04.870 AO: audio waiting for space on soundcard: have 3204 need 6144
3022008-10-04 16:53:04.875 SetAudiotime set=1223164384.875378, audt=33856478 atc=33857053 tb=110500 sb=61348 eds=4800000 abps=4 sf=1
3032008-10-04 16:53:04.875 AO: audio waiting for space on soundcard: have 4188 need 6144
3042008-10-04 16:53:04.879 NVP: Video is 12.287 frames ahead of audio,
305                        doubling video frame interval to slow down.
3062008-10-04 16:53:04.879 GetAudiotime now=1223164384.879944, set=1223164384.875378, ret=4, audt=33856478 sf=1
3072008-10-04 16:53:04.880 NVP: A/V Divergence: 11.6279, Rate: -0.659114, Warpfactor: 1.0025, warpfactor_avg: 0.999996
3082008-10-04 16:53:04.882 SetAudiotime set=1223164384.882366, audt=33856485 atc=33857053 tb=109160 sb=60008 eds=4800000 abps=4 sf=1
3092008-10-04 16:53:04.882 AO: audio waiting for space on soundcard: have 5528 need 6144
3102008-10-04 16:53:04.885 AFD: video timecode 3047142967 3047142967 33857144 33857127
3112008-10-04 16:53:04.887 SetAudiotime set=1223164384.887572, audt=33856490 atc=33857053 tb=108160 sb=59008 eds=4800000 abps=4 sf=1
3122008-10-04 16:53:04.887 GetAudiotime now=1223164384.887653, set=1223164384.887572, ret=0, audt=33856490 sf=1
3132008-10-04 16:53:04.887 AO: Broadcasting free space avail
3142008-10-04 16:53:04.887 WriteAudio: Preparing 6144 bytes (1536 frames)
3152008-10-04 16:53:04.887 SetAudiotime set=1223164384.887792, audt=33856490 atc=33857053 tb=108116 sb=65108 eds=4800000 abps=4 sf=1
3162008-10-04 16:53:04.887 AO: audio waiting for space on soundcard: have 428 need 6144
3172008-10-04 16:53:04.892 SetAudiotime set=1223164384.892898, audt=33856495 atc=33857053 tb=107136 sb=64128 eds=4800000 abps=4 sf=1
3182008-10-04 16:53:04.892 AO: audio waiting for space on soundcard: have 1408 need 6144
3192008-10-04 16:53:04.898 SetAudiotime set=1223164384.898024, audt=33856501 atc=33857053 tb=106152 sb=63144 eds=4800000 abps=4 sf=1
3202008-10-04 16:53:04.898 AO: audio waiting for space on soundcard: have 2392 need 6144
3212008-10-04 16:53:04.903 SetAudiotime set=1223164384.903220, audt=33856506 atc=33857053 tb=105156 sb=62148 eds=4800000 abps=4 sf=1
3222008-10-04 16:53:04.903 AO: audio waiting for space on soundcard: have 3388 need 6144
3232008-10-04 16:53:04.908 SetAudiotime set=1223164384.908381, audt=33856511 atc=33857053 tb=104164 sb=61156 eds=4800000 abps=4 sf=1
3242008-10-04 16:53:04.908 AO: audio waiting for space on soundcard: have 4380 need 6144
3252008-10-04 16:53:04.913 NVP: Video is 11.6279 frames ahead of audio,
326                        doubling video frame interval to slow down.
3272008-10-04 16:53:04.913 GetAudiotime now=1223164384.913321, set=1223164384.908381, ret=4, audt=33856511 sf=1
3282008-10-04 16:53:04.913 NVP: A/V Divergence: 10.8938, Rate: -0.734101, Warpfactor: 1.003, warpfactor_avg: 0.999996
3292008-10-04 16:53:04.916 SetAudiotime set=1223164384.916944, audt=33856520 atc=33857053 tb=102520 sb=59512 eds=4800000 abps=4 sf=1
3302008-10-04 16:53:04.917 AO: audio waiting for space on soundcard: have 6024 need 6144
3312008-10-04 16:53:04.921 AFD: video timecode 3047148973 3047144468 33857160 33857144
3322008-10-04 16:53:04.922 SetAudiotime set=1223164384.922155, audt=33856525 atc=33857053 tb=101520 sb=58512 eds=4800000 abps=4 sf=1
3332008-10-04 16:53:04.922 GetAudiotime now=1223164384.922236, set=1223164384.922155, ret=0, audt=33856525 sf=1
3342008-10-04 16:53:04.922 AO: Broadcasting free space avail
3352008-10-04 16:53:04.922 WriteAudio: Preparing 6144 bytes (1536 frames)
3362008-10-04 16:53:04.922 SetAudiotime set=1223164384.922383, audt=33856525 atc=33857053 tb=101476 sb=64612 eds=4800000 abps=4 sf=1
3372008-10-04 16:53:04.922 AO: audio waiting for space on soundcard: have 924 need 6144
3382008-10-04 16:53:04.927 SetAudiotime set=1223164384.927503, audt=33856530 atc=33857053 tb=100492 sb=63628 eds=4800000 abps=4 sf=1
3392008-10-04 16:53:04.927 AO: audio waiting for space on soundcard: have 1908 need 6144
3402008-10-04 16:53:04.932 SetAudiotime set=1223164384.932683, audt=33856535 atc=33857053 tb=99496 sb=62632 eds=4800000 abps=4 sf=1
3412008-10-04 16:53:04.932 AO: audio waiting for space on soundcard: have 2904 need 6144
3422008-10-04 16:53:04.937 SetAudiotime set=1223164384.937820, audt=33856540 atc=33857053 tb=98512 sb=61648 eds=4800000 abps=4 sf=1
3432008-10-04 16:53:04.937 AO: audio waiting for space on soundcard: have 3888 need 6144
3442008-10-04 16:53:04.942 SetAudiotime set=1223164384.942930, audt=33856546 atc=33857053 tb=97528 sb=60664 eds=4800000 abps=4 sf=1
3452008-10-04 16:53:04.942 AO: audio waiting for space on soundcard: have 4872 need 6144
3462008-10-04 16:53:04.946 NVP: Video is 10.8938 frames ahead of audio,
347                        doubling video frame interval to slow down.
3482008-10-04 16:53:04.946 GetAudiotime now=1223164384.946648, set=1223164384.942930, ret=3, audt=33856546 sf=1
3492008-10-04 16:53:04.946 NVP: A/V Divergence: 10.0734, Rate: -0.820356, Warpfactor: 1.0035, warpfactor_avg: 0.999996
3502008-10-04 16:53:04.949 SetAudiotime set=1223164384.949055, audt=33856552 atc=33857053 tb=96352 sb=59488 eds=4800000 abps=4 sf=1
3512008-10-04 16:53:04.949 AO: audio waiting for space on soundcard: have 6044 need 6144
3522008-10-04 16:53:04.949 AFD: video timecode 3047145970 3047145970 33857177 33857160
3532008-10-04 16:53:04.954 SetAudiotime set=1223164384.954264, audt=33856557 atc=33857053 tb=95352 sb=58488 eds=4800000 abps=4 sf=1
3542008-10-04 16:53:04.954 GetAudiotime now=1223164384.954326, set=1223164384.954264, ret=0, audt=33856557 sf=1
3552008-10-04 16:53:04.954 AO: Broadcasting free space avail
3562008-10-04 16:53:04.954 WriteAudio: Preparing 6144 bytes (1536 frames)
3572008-10-04 16:53:04.954 SetAudiotime set=1223164384.954470, audt=33856557 atc=33857053 tb=95312 sb=64592 eds=4800000 abps=4 sf=1
3582008-10-04 16:53:04.954 AO: audio waiting for space on soundcard: have 944 need 6144
3592008-10-04 16:53:04.959 SetAudiotime set=1223164384.959592, audt=33856562 atc=33857053 tb=94328 sb=63608 eds=4800000 abps=4 sf=1
3602008-10-04 16:53:04.959 AO: audio waiting for space on soundcard: have 1924 need 6144
3612008-10-04 16:53:04.964 SetAudiotime set=1223164384.964773, audt=33856567 atc=33857053 tb=93336 sb=62616 eds=4800000 abps=4 sf=1
3622008-10-04 16:53:04.964 AO: audio waiting for space on soundcard: have 2920 need 6144
3632008-10-04 16:53:04.969 SetAudiotime set=1223164384.969937, audt=33856573 atc=33857053 tb=92344 sb=61624 eds=4800000 abps=4 sf=1
3642008-10-04 16:53:04.970 AO: audio waiting for space on soundcard: have 3912 need 6144
3652008-10-04 16:53:04.975 SetAudiotime set=1223164384.975141, audt=33856578 atc=33857053 tb=91344 sb=60624 eds=4800000 abps=4 sf=1
3662008-10-04 16:53:04.975 AO: audio waiting for space on soundcard: have 4912 need 6144
3672008-10-04 16:53:04.980 NVP: Video is 10.0734 frames ahead of audio,
368                        doubling video frame interval to slow down.
3692008-10-04 16:53:04.980 GetAudiotime now=1223164384.980222, set=1223164384.975141, ret=5, audt=33856578 sf=1
3702008-10-04 16:53:04.980 NVP: A/V Divergence: 9.20344, Rate: -0.869987, Warpfactor: 1.004, warpfactor_avg: 0.999997
3712008-10-04 16:53:04.982 SetAudiotime set=1223164384.982727, audt=33856585 atc=33857053 tb=89888 sb=59168 eds=4800000 abps=4 sf=1
3722008-10-04 16:53:04.982 GetAudiotime now=1223164384.982878, set=1223164384.982727, ret=0, audt=33856585 sf=1
3732008-10-04 16:53:04.982 AO: Broadcasting free space avail
3742008-10-04 16:53:04.982 WriteAudio: Preparing 6144 bytes (1536 frames)
3752008-10-04 16:53:04.983 SetAudiotime set=1223164384.983034, audt=33856586 atc=33857053 tb=89828 sb=65252 eds=4800000 abps=4 sf=1
3762008-10-04 16:53:04.983 AO: audio waiting for space on soundcard: have 284 need 6144
3772008-10-04 16:53:04.983 AFD: video timecode 3047147471 3047147471 33857194 33857177
3782008-10-04 16:53:04.988 SetAudiotime set=1223164384.988178, audt=33856591 atc=33857053 tb=88840 sb=64264 eds=4800000 abps=4 sf=1
3792008-10-04 16:53:04.988 AO: audio waiting for space on soundcard: have 1272 need 6144
3802008-10-04 16:53:04.993 SetAudiotime set=1223164384.993364, audt=33856596 atc=33857053 tb=87844 sb=63268 eds=4800000 abps=4 sf=1
3812008-10-04 16:53:04.993 AO: audio waiting for space on soundcard: have 2268 need 6144
3822008-10-04 16:53:04.998 SetAudiotime set=1223164384.998482, audt=33856601 atc=33857053 tb=86864 sb=62288 eds=4800000 abps=4 sf=1
3832008-10-04 16:53:04.998 AO: audio waiting for space on soundcard: have 3248 need 6144
3842008-10-04 16:53:05.003 SetAudiotime set=1223164385.3591, audt=33856606 atc=33857053 tb=85880 sb=61304 eds=4800000 abps=4 sf=1
3852008-10-04 16:53:05.003 AO: audio waiting for space on soundcard: have 4232 need 6144
3862008-10-04 16:53:05.008 SetAudiotime set=1223164385.8711, audt=33856611 atc=33857053 tb=84900 sb=60324 eds=4800000 abps=4 sf=1
3872008-10-04 16:53:05.008 AO: audio waiting for space on soundcard: have 5212 need 6144
3882008-10-04 16:53:05.013 NVP: Video is 9.20344 frames ahead of audio,
389                        doubling video frame interval to slow down.
3902008-10-04 16:53:05.013 GetAudiotime now=1223164385.13520, set=1223164385.8711, ret=4, audt=33856611 sf=1
3912008-10-04 16:53:05.013 NVP: A/V Divergence: 8.32614, Rate: -0.8773, Warpfactor: 1.0045, warpfactor_avg: 0.999997
3922008-10-04 16:53:05.017 SetAudiotime set=1223164385.17397, audt=33856620 atc=33857053 tb=83232 sb=58656 eds=4800000 abps=4 sf=1
3932008-10-04 16:53:05.017 GetAudiotime now=1223164385.17543, set=1223164385.17397, ret=0, audt=33856620 sf=1
3942008-10-04 16:53:05.017 AO: Broadcasting free space avail
3952008-10-04 16:53:05.017 WriteAudio: Preparing 6144 bytes (1536 frames)
3962008-10-04 16:53:05.017 SetAudiotime set=1223164385.17700, audt=33856620 atc=33857053 tb=83172 sb=64740 eds=4800000 abps=4 sf=1
3972008-10-04 16:53:05.017 AO: audio waiting for space on soundcard: have 796 need 6144
3982008-10-04 16:53:05.017 AFD: video timecode 3047153477 3047148973 33857210 0
3992008-10-04 16:53:05.022 SetAudiotime set=1223164385.22831, audt=33856625 atc=33857053 tb=82188 sb=63756 eds=4800000 abps=4 sf=1
4002008-10-04 16:53:05.022 AO: audio waiting for space on soundcard: have 1780 need 6144
4012008-10-04 16:53:05.028 SetAudiotime set=1223164385.28019, audt=33856631 atc=33857053 tb=81192 sb=62760 eds=4800000 abps=4 sf=1
4022008-10-04 16:53:05.028 AO: audio waiting for space on soundcard: have 2776 need 6144
4032008-10-04 16:53:05.033 SetAudiotime set=1223164385.33151, audt=33856636 atc=33857053 tb=80204 sb=61772 eds=4800000 abps=4 sf=1
4042008-10-04 16:53:05.033 AO: audio waiting for space on soundcard: have 3764 need 6144
4052008-10-04 16:53:05.038 SetAudiotime set=1223164385.38288, audt=33856641 atc=33857053 tb=79220 sb=60788 eds=4800000 abps=4 sf=1
4062008-10-04 16:53:05.038 AO: audio waiting for space on soundcard: have 4748 need 6144
4072008-10-04 16:53:05.043 SetAudiotime set=1223164385.43454, audt=33856646 atc=33857053 tb=78228 sb=59796 eds=4800000 abps=4 sf=1
4082008-10-04 16:53:05.043 AO: audio waiting for space on soundcard: have 5740 need 6144
4092008-10-04 16:53:05.046 NVP: Video is 8.32614 frames ahead of audio,
410                        doubling video frame interval to slow down.
4112008-10-04 16:53:05.046 GetAudiotime now=1223164385.46900, set=1223164385.43454, ret=3, audt=33856646 sf=1
4122008-10-04 16:53:05.046 NVP: A/V Divergence: 7.39843, Rate: -0.927711, Warpfactor: 1.00501, warpfactor_avg: 0.999997
4132008-10-04 16:53:05.050 SetAudiotime set=1223164385.49992, audt=33856653 atc=33857053 tb=76972 sb=58540 eds=4800000 abps=4 sf=1
4142008-10-04 16:53:05.050 GetAudiotime now=1223164385.50151, set=1223164385.49992, ret=0, audt=33856653 sf=1
4152008-10-04 16:53:05.050 AO: Broadcasting free space avail
4162008-10-04 16:53:05.050 WriteAudio: Preparing 6144 bytes (1536 frames)
4172008-10-04 16:53:05.050 SetAudiotime set=1223164385.50306, audt=33856653 atc=33857053 tb=76912 sb=64624 eds=4800000 abps=4 sf=1
4182008-10-04 16:53:05.050 AO: audio waiting for space on soundcard: have 912 need 6144
4192008-10-04 16:53:05.051 AFD: video timecode 3047150474 3047150474 33857227 33857210
4202008-10-04 16:53:05.055 SetAudiotime set=1223164385.55438, audt=33856658 atc=33857053 tb=75924 sb=63636 eds=4800000 abps=4 sf=1
4212008-10-04 16:53:05.055 AO: audio waiting for space on soundcard: have 1896 need 6144
4222008-10-04 16:53:05.060 SetAudiotime set=1223164385.60624, audt=33856663 atc=33857053 tb=74928 sb=62640 eds=4800000 abps=4 sf=1
4232008-10-04 16:53:05.060 AO: audio waiting for space on soundcard: have 2892 need 6144
4242008-10-04 16:53:05.065 SetAudiotime set=1223164385.65740, audt=33856668 atc=33857053 tb=73948 sb=61660 eds=4800000 abps=4 sf=1
4252008-10-04 16:53:05.065 AO: audio waiting for space on soundcard: have 3876 need 6144
4262008-10-04 16:53:05.070 SetAudiotime set=1223164385.70853, audt=33856673 atc=33857053 tb=72964 sb=60676 eds=4800000 abps=4 sf=1
4272008-10-04 16:53:05.070 AO: audio waiting for space on soundcard: have 4856 need 6144
4282008-10-04 16:53:05.075 SetAudiotime set=1223164385.75964, audt=33856679 atc=33857053 tb=71984 sb=59696 eds=4800000 abps=4 sf=1
4292008-10-04 16:53:05.076 AO: audio waiting for space on soundcard: have 5840 need 6144
4302008-10-04 16:53:05.080 NVP: Video is 7.39843 frames ahead of audio,
431                        doubling video frame interval to slow down.
4322008-10-04 16:53:05.080 GetAudiotime now=1223164385.80277, set=1223164385.75964, ret=4, audt=33856679 sf=1
4332008-10-04 16:53:05.080 NVP: A/V Divergence: 6.44794, Rate: -0.950489, Warpfactor: 1.00551, warpfactor_avg: 0.999997
4342008-10-04 16:53:05.083 SetAudiotime set=1223164385.83224, audt=33856686 atc=33857053 tb=70592 sb=58304 eds=4800000 abps=4 sf=1
4352008-10-04 16:53:05.083 GetAudiotime now=1223164385.83371, set=1223164385.83224, ret=0, audt=33856686 sf=1
4362008-10-04 16:53:05.083 AO: Broadcasting free space avail
4372008-10-04 16:53:05.083 WriteAudio: Preparing 6144 bytes (1536 frames)
4382008-10-04 16:53:05.083 SetAudiotime set=1223164385.83527, audt=33856686 atc=33857053 tb=70532 sb=64388 eds=4800000 abps=4 sf=1
4392008-10-04 16:53:05.083 AO: audio waiting for space on soundcard: have 1148 need 6144
4402008-10-04 16:53:05.084 AFD: video timecode 3047151976 3047151976 33857244 33857227
4412008-10-04 16:53:05.088 SetAudiotime set=1223164385.88658, audt=33856691 atc=33857053 tb=69548 sb=63404 eds=4800000 abps=4 sf=1
4422008-10-04 16:53:05.088 AO: audio waiting for space on soundcard: have 2132 need 6144
4432008-10-04 16:53:05.093 SetAudiotime set=1223164385.93840, audt=33856696 atc=33857053 tb=68552 sb=62408 eds=4800000 abps=4 sf=1
4442008-10-04 16:53:05.093 AO: audio waiting for space on soundcard: have 3128 need 6144
4452008-10-04 16:53:05.098 SetAudiotime set=1223164385.98969, audt=33856702 atc=33857053 tb=67568 sb=61424 eds=4800000 abps=4 sf=1
4462008-10-04 16:53:05.099 AO: audio waiting for space on soundcard: have 4112 need 6144
4472008-10-04 16:53:05.104 SetAudiotime set=1223164385.104166, audt=33856707 atc=33857053 tb=66568 sb=60424 eds=4800000 abps=4 sf=1
4482008-10-04 16:53:05.104 AO: audio waiting for space on soundcard: have 5112 need 6144
4492008-10-04 16:53:05.109 SetAudiotime set=1223164385.109282, audt=33856712 atc=33857053 tb=65588 sb=59444 eds=4800000 abps=4 sf=1
4502008-10-04 16:53:05.109 AO: audio waiting for space on soundcard: have 6092 need 6144
4512008-10-04 16:53:05.113 NVP: Video is 6.44794 frames ahead of audio,
452                        doubling video frame interval to slow down.
4532008-10-04 16:53:05.113 GetAudiotime now=1223164385.113666, set=1223164385.109282, ret=4, audt=33856712 sf=1
4542008-10-04 16:53:05.113 NVP: A/V Divergence: 5.49529, Rate: -0.952646, Warpfactor: 1.00601, warpfactor_avg: 0.999997
4552008-10-04 16:53:05.116 SetAudiotime set=1223164385.116661, audt=33856719 atc=33857053 tb=64172 sb=58028 eds=4800000 abps=4 sf=1
4562008-10-04 16:53:05.116 GetAudiotime now=1223164385.116805, set=1223164385.116661, ret=0, audt=33856719 sf=1
4572008-10-04 16:53:05.116 AO: Broadcasting free space avail
4582008-10-04 16:53:05.116 WriteAudio: Preparing 6144 bytes (1536 frames)
4592008-10-04 16:53:05.116 SetAudiotime set=1223164385.116957, audt=33856720 atc=33857053 tb=64112 sb=64112 eds=4800000 abps=4 sf=1
4602008-10-04 16:53:05.117 AFD: video timecode 3047157982 3047153477 33857260 33857244
4612008-10-04 16:53:05.119 SetAudiotime set=1223164385.119040, audt=33856722 atc=33857053 tb=63712 sb=63712 eds=4800000 abps=4 sf=1
4622008-10-04 16:53:05.121 SetAudiotime set=1223164385.121147, audt=33856724 atc=33857053 tb=63308 sb=63308 eds=4800000 abps=4 sf=1
4632008-10-04 16:53:05.123 SetAudiotime set=1223164385.123227, audt=33856726 atc=33857053 tb=62908 sb=62908 eds=4800000 abps=4 sf=1
4642008-10-04 16:53:05.125 SetAudiotime set=1223164385.125306, audt=33856728 atc=33857053 tb=62512 sb=62512 eds=4800000 abps=4 sf=1
4652008-10-04 16:53:05.127 SetAudiotime set=1223164385.127387, audt=33856730 atc=33857053 tb=62112 sb=62112 eds=4800000 abps=4 sf=1
4662008-10-04 16:53:05.129 SetAudiotime set=1223164385.129460, audt=33856732 atc=33857053 tb=61712 sb=61712 eds=4800000 abps=4 sf=1
4672008-10-04 16:53:05.131 SetAudiotime set=1223164385.131534, audt=33856734 atc=33857053 tb=61316 sb=61316 eds=4800000 abps=4 sf=1
4682008-10-04 16:53:05.133 SetAudiotime set=1223164385.133608, audt=33856736 atc=33857053 tb=60916 sb=60916 eds=4800000 abps=4 sf=1
4692008-10-04 16:53:05.135 SetAudiotime set=1223164385.135682, audt=33856738 atc=33857053 tb=60516 sb=60516 eds=4800000 abps=4 sf=1
4702008-10-04 16:53:05.137 SetAudiotime set=1223164385.137755, audt=33856740 atc=33857053 tb=60120 sb=60120 eds=4800000 abps=4 sf=1
4712008-10-04 16:53:05.139 SetAudiotime set=1223164385.139825, audt=33856742 atc=33857053 tb=59724 sb=59724 eds=4800000 abps=4 sf=1
4722008-10-04 16:53:05.141 SetAudiotime set=1223164385.141899, audt=33856745 atc=33857053 tb=59324 sb=59324 eds=4800000 abps=4 sf=1
4732008-10-04 16:53:05.143 SetAudiotime set=1223164385.143991, audt=33856747 atc=33857053 tb=58924 sb=58924 eds=4800000 abps=4 sf=1
4742008-10-04 16:53:05.146 SetAudiotime set=1223164385.146143, audt=33856749 atc=33857053 tb=58508 sb=58508 eds=4800000 abps=4 sf=1
4752008-10-04 16:53:05.148 SetAudiotime set=1223164385.148413, audt=33856751 atc=33857053 tb=58072 sb=58072 eds=4800000 abps=4 sf=1
4762008-10-04 16:53:05.148 NVP: Video is 5.49529 frames ahead of audio,
477                        doubling video frame interval to slow down.
4782008-10-04 16:53:05.148 GetAudiotime now=1223164385.148796, set=1223164385.148413, ret=0, audt=33856751 sf=1
4792008-10-04 16:53:05.148 NVP: A/V Divergence: 4.51106, Rate: -0.984235, Warpfactor: 1.00651, warpfactor_avg: 0.999997
4802008-10-04 16:53:05.151 SetAudiotime set=1223164385.151518, audt=33856754 atc=33857053 tb=57476 sb=57476 eds=4800000 abps=4 sf=1
4812008-10-04 16:53:05.152 AFD: video timecode 3047154979 3047154979 33857277 33857260
4822008-10-04 16:53:05.153 SetAudiotime set=1223164385.153681, audt=33856756 atc=33857053 tb=57060 sb=57060 eds=4800000 abps=4 sf=1
4832008-10-04 16:53:05.155 SetAudiotime set=1223164385.155773, audt=33856758 atc=33857053 tb=56660 sb=56660 eds=4800000 abps=4 sf=1
4842008-10-04 16:53:05.157 SetAudiotime set=1223164385.157921, audt=33856761 atc=33857053 tb=56248 sb=56248 eds=4800000 abps=4 sf=1
4852008-10-04 16:53:05.160 SetAudiotime set=1223164385.160092, audt=33856763 atc=33857053 tb=55832 sb=55832 eds=4800000 abps=4 sf=1
4862008-10-04 16:53:05.162 SetAudiotime set=1223164385.162181, audt=33856765 atc=33857053 tb=55428 sb=55428 eds=4800000 abps=4 sf=1
4872008-10-04 16:53:05.164 SetAudiotime set=1223164385.164257, audt=33856767 atc=33857053 tb=55032 sb=55032 eds=4800000 abps=4 sf=1
4882008-10-04 16:53:05.166 SetAudiotime set=1223164385.166334, audt=33856769 atc=33857053 tb=54632 sb=54632 eds=4800000 abps=4 sf=1
4892008-10-04 16:53:05.168 SetAudiotime set=1223164385.168410, audt=33856771 atc=33857053 tb=54232 sb=54232 eds=4800000 abps=4 sf=1
4902008-10-04 16:53:05.170 SetAudiotime set=1223164385.170498, audt=33856773 atc=33857053 tb=53832 sb=53832 eds=4800000 abps=4 sf=1
4912008-10-04 16:53:05.172 SetAudiotime set=1223164385.172626, audt=33856775 atc=33857053 tb=53424 sb=53424 eds=4800000 abps=4 sf=1
4922008-10-04 16:53:05.174 SetAudiotime set=1223164385.174704, audt=33856777 atc=33857053 tb=53024 sb=53024 eds=4800000 abps=4 sf=1
4932008-10-04 16:53:05.176 SetAudiotime set=1223164385.176785, audt=33856779 atc=33857053 tb=52624 sb=52624 eds=4800000 abps=4 sf=1
4942008-10-04 16:53:05.178 SetAudiotime set=1223164385.178856, audt=33856781 atc=33857053 tb=52228 sb=52228 eds=4800000 abps=4 sf=1
4952008-10-04 16:53:05.180 NVP: Video is 4.51106 frames ahead of audio,
496                        doubling video frame interval to slow down.
4972008-10-04 16:53:05.180 GetAudiotime now=1223164385.180300, set=1223164385.178856, ret=1, audt=33856781 sf=1
4982008-10-04 16:53:05.180 NVP: A/V Divergence: 3.5481, Rate: -0.962956, Warpfactor: 1.00702, warpfactor_avg: 0.999997
4992008-10-04 16:53:05.182 SetAudiotime set=1223164385.182922, audt=33856786 atc=33857053 tb=51448 sb=51448 eds=4800000 abps=4 sf=1
5002008-10-04 16:53:05.183 AFD: video timecode 3047156480 3047156480 33857294 33857277
5012008-10-04 16:53:05.185 SetAudiotime set=1223164385.185088, audt=33856788 atc=33857053 tb=51032 sb=51032 eds=4800000 abps=4 sf=1
5022008-10-04 16:53:05.187 SetAudiotime set=1223164385.187166, audt=33856790 atc=33857053 tb=50632 sb=50632 eds=4800000 abps=4 sf=1
5032008-10-04 16:53:05.189 SetAudiotime set=1223164385.189241, audt=33856792 atc=33857053 tb=50232 sb=50232 eds=4800000 abps=4 sf=1
5042008-10-04 16:53:05.191 SetAudiotime set=1223164385.191319, audt=33856794 atc=33857053 tb=49836 sb=49836 eds=4800000 abps=4 sf=1
5052008-10-04 16:53:05.193 SetAudiotime set=1223164385.193398, audt=33856796 atc=33857053 tb=49436 sb=49436 eds=4800000 abps=4 sf=1
5062008-10-04 16:53:05.195 SetAudiotime set=1223164385.195475, audt=33856798 atc=33857053 tb=49036 sb=49036 eds=4800000 abps=4 sf=1
5072008-10-04 16:53:05.197 SetAudiotime set=1223164385.197552, audt=33856800 atc=33857053 tb=48636 sb=48636 eds=4800000 abps=4 sf=1
5082008-10-04 16:53:05.199 SetAudiotime set=1223164385.199633, audt=33856802 atc=33857053 tb=48240 sb=48240 eds=4800000 abps=4 sf=1
5092008-10-04 16:53:05.201 SetAudiotime set=1223164385.201746, audt=33856804 atc=33857053 tb=47832 sb=47832 eds=4800000 abps=4 sf=1
5102008-10-04 16:53:05.203 SetAudiotime set=1223164385.203886, audt=33856807 atc=33857053 tb=47420 sb=47420 eds=4800000 abps=4 sf=1
5112008-10-04 16:53:05.205 SetAudiotime set=1223164385.205975, audt=33856809 atc=33857053 tb=47020 sb=47020 eds=4800000 abps=4 sf=1
5122008-10-04 16:53:05.208 SetAudiotime set=1223164385.208054, audt=33856811 atc=33857053 tb=46620 sb=46620 eds=4800000 abps=4 sf=1
5132008-10-04 16:53:05.210 SetAudiotime set=1223164385.210142, audt=33856813 atc=33857053 tb=46220 sb=46220 eds=4800000 abps=4 sf=1
5142008-10-04 16:53:05.212 SetAudiotime set=1223164385.212218, audt=33856815 atc=33857053 tb=45820 sb=45820 eds=4800000 abps=4 sf=1
5152008-10-04 16:53:05.213 NVP: Video is 3.5481 frames ahead of audio,
516                        doubling video frame interval to slow down.
5172008-10-04 16:53:05.213 GetAudiotime now=1223164385.213915, set=1223164385.212218, ret=1, audt=33856815 sf=1
5182008-10-04 16:53:05.214 NVP: A/V Divergence: 2.57112, Rate: -0.976983, Warpfactor: 1.00752, warpfactor_avg: 0.999998
5192008-10-04 16:53:05.216 SetAudiotime set=1223164385.216137, audt=33856819 atc=33857053 tb=45068 sb=45068 eds=4800000 abps=4 sf=1
5202008-10-04 16:53:05.216 AFD: video timecode 3047162486 3047157982 33857310 33857294
5212008-10-04 16:53:05.218 SetAudiotime set=1223164385.218308, audt=33856821 atc=33857053 tb=44652 sb=44652 eds=4800000 abps=4 sf=1
5222008-10-04 16:53:05.220 SetAudiotime set=1223164385.220397, audt=33856823 atc=33857053 tb=44252 sb=44252 eds=4800000 abps=4 sf=1
5232008-10-04 16:53:05.222 SetAudiotime set=1223164385.222473, audt=33856825 atc=33857053 tb=43852 sb=43852 eds=4800000 abps=4 sf=1
5242008-10-04 16:53:05.224 SetAudiotime set=1223164385.224557, audt=33856827 atc=33857053 tb=43452 sb=43452 eds=4800000 abps=4 sf=1
5252008-10-04 16:53:05.226 SetAudiotime set=1223164385.226643, audt=33856829 atc=33857053 tb=43052 sb=43052 eds=4800000 abps=4 sf=1
5262008-10-04 16:53:05.228 SetAudiotime set=1223164385.228733, audt=33856831 atc=33857053 tb=42652 sb=42652 eds=4800000 abps=4 sf=1
5272008-10-04 16:53:05.230 SetAudiotime set=1223164385.230877, audt=33856833 atc=33857053 tb=42240 sb=42240 eds=4800000 abps=4 sf=1
5282008-10-04 16:53:05.233 SetAudiotime set=1223164385.233010, audt=33856836 atc=33857053 tb=41828 sb=41828 eds=4800000 abps=4 sf=1
5292008-10-04 16:53:05.235 SetAudiotime set=1223164385.235110, audt=33856838 atc=33857053 tb=41424 sb=41424 eds=4800000 abps=4 sf=1
5302008-10-04 16:53:05.237 SetAudiotime set=1223164385.237185, audt=33856840 atc=33857053 tb=41028 sb=41028 eds=4800000 abps=4 sf=1
5312008-10-04 16:53:05.239 SetAudiotime set=1223164385.239276, audt=33856842 atc=33857053 tb=40628 sb=40628 eds=4800000 abps=4 sf=1
5322008-10-04 16:53:05.241 SetAudiotime set=1223164385.241355, audt=33856844 atc=33857053 tb=40228 sb=40228 eds=4800000 abps=4 sf=1
5332008-10-04 16:53:05.243 SetAudiotime set=1223164385.243434, audt=33856846 atc=33857053 tb=39828 sb=39828 eds=4800000 abps=4 sf=1
5342008-10-04 16:53:05.245 SetAudiotime set=1223164385.245512, audt=33856848 atc=33857053 tb=39428 sb=39428 eds=4800000 abps=4 sf=1
5352008-10-04 16:53:05.246 GetAudiotime now=1223164385.246675, set=1223164385.245512, ret=1, audt=33856848 sf=1
5362008-10-04 16:53:05.246 NVP: A/V Divergence: 1.59863, Rate: -0.972487, Warpfactor: 1.00702, warpfactor_avg: 0.999998
5372008-10-04 16:53:05.249 SetAudiotime set=1223164385.249249, audt=33856852 atc=33857053 tb=38712 sb=38712 eds=4800000 abps=4 sf=1
5382008-10-04 16:53:05.249 AFD: video timecode 3047159483 3047159483 33857327 33857310
5392008-10-04 16:53:05.251 SetAudiotime set=1223164385.251423, audt=33856854 atc=33857053 tb=38292 sb=38292 eds=4800000 abps=4 sf=1
5402008-10-04 16:53:05.253 SetAudiotime set=1223164385.253503, audt=33856856 atc=33857053 tb=37896 sb=37896 eds=4800000 abps=4 sf=1
5412008-10-04 16:53:05.255 SetAudiotime set=1223164385.255584, audt=33856858 atc=33857053 tb=37496 sb=37496 eds=4800000 abps=4 sf=1
5422008-10-04 16:53:05.257 SetAudiotime set=1223164385.257669, audt=33856860 atc=33857053 tb=37096 sb=37096 eds=4800000 abps=4 sf=1
5432008-10-04 16:53:05.259 SetAudiotime set=1223164385.259815, audt=33856862 atc=33857053 tb=36684 sb=36684 eds=4800000 abps=4 sf=1
5442008-10-04 16:53:05.261 SetAudiotime set=1223164385.261964, audt=33856865 atc=33857053 tb=36268 sb=36268 eds=4800000 abps=4 sf=1
5452008-10-04 16:53:05.264 SetAudiotime set=1223164385.264063, audt=33856867 atc=33857053 tb=35868 sb=35868 eds=4800000 abps=4 sf=1
5462008-10-04 16:53:05.266 SetAudiotime set=1223164385.266145, audt=33856869 atc=33857053 tb=35468 sb=35468 eds=4800000 abps=4 sf=1
5472008-10-04 16:53:05.268 SetAudiotime set=1223164385.268223, audt=33856871 atc=33857053 tb=35068 sb=35068 eds=4800000 abps=4 sf=1
5482008-10-04 16:53:05.270 SetAudiotime set=1223164385.270305, audt=33856873 atc=33857053 tb=34668 sb=34668 eds=4800000 abps=4 sf=1
5492008-10-04 16:53:05.272 SetAudiotime set=1223164385.272415, audt=33856875 atc=33857053 tb=34264 sb=34264 eds=4800000 abps=4 sf=1
5502008-10-04 16:53:05.274 SetAudiotime set=1223164385.274555, audt=33856877 atc=33857053 tb=33852 sb=33852 eds=4800000 abps=4 sf=1
5512008-10-04 16:53:05.276 SetAudiotime set=1223164385.276647, audt=33856879 atc=33857053 tb=33452 sb=33452 eds=4800000 abps=4 sf=1
5522008-10-04 16:53:05.278 SetAudiotime set=1223164385.278721, audt=33856881 atc=33857053 tb=33052 sb=33052 eds=4800000 abps=4 sf=1
5532008-10-04 16:53:05.279 GetAudiotime now=1223164385.279975, set=1223164385.278721, ret=1, audt=33856881 sf=1
5542008-10-04 16:53:05.280 NVP: A/V Divergence: 0.614518, Rate: -0.984116, Warpfactor: 1.00651, warpfactor_avg: 0.999998
5552008-10-04 16:53:05.282 AFD: video timecode 3047160985 3047160985 33857344 33857327
5562008-10-04 16:53:05.282 SetAudiotime set=1223164385.282463, audt=33856885 atc=33857053 tb=32332 sb=32332 eds=4800000 abps=4 sf=1
5572008-10-04 16:53:05.284 SetAudiotime set=1223164385.284563, audt=33856887 atc=33857053 tb=31932 sb=31932 eds=4800000 abps=4 sf=1
5582008-10-04 16:53:05.286 SetAudiotime set=1223164385.286651, audt=33856889 atc=33857053 tb=31528 sb=31528 eds=4800000 abps=4 sf=1
5592008-10-04 16:53:05.288 SetAudiotime set=1223164385.288788, audt=33856891 atc=33857053 tb=31120 sb=31120 eds=4800000 abps=4 sf=1
5602008-10-04 16:53:05.290 SetAudiotime set=1223164385.290916, audt=33856894 atc=33857053 tb=30712 sb=30712 eds=4800000 abps=4 sf=1
5612008-10-04 16:53:05.293 SetAudiotime set=1223164385.293015, audt=33856896 atc=33857053 tb=30308 sb=30308 eds=4800000 abps=4 sf=1
5622008-10-04 16:53:05.295 SetAudiotime set=1223164385.295099, audt=33856898 atc=33857053 tb=29908 sb=29908 eds=4800000 abps=4 sf=1
5632008-10-04 16:53:05.296 GetAudiotime now=1223164385.296550, set=1223164385.295099, ret=1, audt=33856898 sf=1
5642008-10-04 16:53:05.296 NVP: A/V Divergence: -0.123539, Rate: -0.738057, Warpfactor: 1.00601, warpfactor_avg: 0.999998
5652008-10-04 16:53:05.299 SetAudiotime set=1223164385.299286, audt=33856902 atc=33857053 tb=29104 sb=29104 eds=4800000 abps=4 sf=1
5662008-10-04 16:53:05.299 AFD: video timecode 3047166991 3047162486 33857360 33857344
5672008-10-04 16:53:05.301 SetAudiotime set=1223164385.301458, audt=33856904 atc=33857053 tb=28688 sb=28688 eds=4800000 abps=4 sf=1
5682008-10-04 16:53:05.303 SetAudiotime set=1223164385.303535, audt=33856906 atc=33857053 tb=28288 sb=28288 eds=4800000 abps=4 sf=1
5692008-10-04 16:53:05.305 SetAudiotime set=1223164385.305620, audt=33856908 atc=33857053 tb=27888 sb=27888 eds=4800000 abps=4 sf=1
5702008-10-04 16:53:05.307 SetAudiotime set=1223164385.307699, audt=33856910 atc=33857053 tb=27488 sb=27488 eds=4800000 abps=4 sf=1
5712008-10-04 16:53:05.309 SetAudiotime set=1223164385.309795, audt=33856912 atc=33857053 tb=27084 sb=27084 eds=4800000 abps=4 sf=1
5722008-10-04 16:53:05.311 SetAudiotime set=1223164385.311869, audt=33856914 atc=33857053 tb=26688 sb=26688 eds=4800000 abps=4 sf=1
5732008-10-04 16:53:05.313 GetAudiotime now=1223164385.313400, set=1223164385.311869, ret=1, audt=33856914 sf=1
5742008-10-04 16:53:05.313 NVP: A/V Divergence: -0.662051, Rate: -0.538512, Warpfactor: 1.00551, warpfactor_avg: 0.999998
5752008-10-04 16:53:05.315 AFD: video timecode 3047163988 3047163988 33857377 33857360
5762008-10-04 16:53:05.315 SetAudiotime set=1223164385.315781, audt=33856918 atc=33857053 tb=25936 sb=25936 eds=4800000 abps=4 sf=1
5772008-10-04 16:53:05.317 SetAudiotime set=1223164385.317892, audt=33856921 atc=33857053 tb=25532 sb=25532 eds=4800000 abps=4 sf=1
5782008-10-04 16:53:05.320 SetAudiotime set=1223164385.320041, audt=33856923 atc=33857053 tb=25120 sb=25120 eds=4800000 abps=4 sf=1
5792008-10-04 16:53:05.322 SetAudiotime set=1223164385.322187, audt=33856925 atc=33857053 tb=24704 sb=24704 eds=4800000 abps=4 sf=1
5802008-10-04 16:53:05.324 SetAudiotime set=1223164385.324271, audt=33856927 atc=33857053 tb=24304 sb=24304 eds=4800000 abps=4 sf=1
5812008-10-04 16:53:05.326 SetAudiotime set=1223164385.326355, audt=33856929 atc=33857053 tb=23904 sb=23904 eds=4800000 abps=4 sf=1
5822008-10-04 16:53:05.328 SetAudiotime set=1223164385.328428, audt=33856931 atc=33857053 tb=23508 sb=23508 eds=4800000 abps=4 sf=1
5832008-10-04 16:53:05.330 GetAudiotime now=1223164385.330048, set=1223164385.328428, ret=1, audt=33856931 sf=1
5842008-10-04 16:53:05.330 NVP: A/V Divergence: -1.08092, Rate: -0.418869, Warpfactor: 1.005, warpfactor_avg: 0.999999
5852008-10-04 16:53:05.332 SetAudiotime set=1223164385.332752, audt=33856935 atc=33857053 tb=22676 sb=22676 eds=4800000 abps=4 sf=1
5862008-10-04 16:53:05.333 AFD: video timecode 3047165489 3047165489 33857394 33857377
5872008-10-04 16:53:05.334 SetAudiotime set=1223164385.334927, audt=33856938 atc=33857053 tb=22260 sb=22260 eds=4800000 abps=4 sf=1
5882008-10-04 16:53:05.337 SetAudiotime set=1223164385.337041, audt=33856940 atc=33857053 tb=21852 sb=21852 eds=4800000 abps=4 sf=1
5892008-10-04 16:53:05.339 SetAudiotime set=1223164385.339182, audt=33856942 atc=33857053 tb=21444 sb=21444 eds=4800000 abps=4 sf=1
5902008-10-04 16:53:05.341 SetAudiotime set=1223164385.341269, audt=33856944 atc=33857053 tb=21040 sb=21040 eds=4800000 abps=4 sf=1
5912008-10-04 16:53:05.343 SetAudiotime set=1223164385.343354, audt=33856946 atc=33857053 tb=20640 sb=20640 eds=4800000 abps=4 sf=1
5922008-10-04 16:53:05.345 SetAudiotime set=1223164385.345426, audt=33856948 atc=33857053 tb=20244 sb=20244 eds=4800000 abps=4 sf=1
5932008-10-04 16:53:05.346 GetAudiotime now=1223164385.346641, set=1223164385.345426, ret=1, audt=33856948 sf=1
5942008-10-04 16:53:05.346 NVP: A/V Divergence: -1.39507, Rate: -0.314152, Warpfactor: 1.0045, warpfactor_avg: 0.999999
5952008-10-04 16:53:05.349 SetAudiotime set=1223164385.349336, audt=33856952 atc=33857053 tb=19492 sb=19492 eds=4800000 abps=4 sf=1
5962008-10-04 16:53:05.350 AFD: video timecode 3047171495 3047166991 33857411 33857394
5972008-10-04 16:53:05.351 SetAudiotime set=1223164385.351511, audt=33856954 atc=33857053 tb=19076 sb=19076 eds=4800000 abps=4 sf=1
5982008-10-04 16:53:05.353 SetAudiotime set=1223164385.353631, audt=33856956 atc=33857053 tb=18668 sb=18668 eds=4800000 abps=4 sf=1
5992008-10-04 16:53:05.355 SetAudiotime set=1223164385.355763, audt=33856958 atc=33857053 tb=18260 sb=18260 eds=4800000 abps=4 sf=1
6002008-10-04 16:53:05.357 SetAudiotime set=1223164385.357849, audt=33856960 atc=33857053 tb=17860 sb=17860 eds=4800000 abps=4 sf=1
6012008-10-04 16:53:05.359 SetAudiotime set=1223164385.359939, audt=33856963 atc=33857053 tb=17456 sb=17456 eds=4800000 abps=4 sf=1
6022008-10-04 16:53:05.362 SetAudiotime set=1223164385.362010, audt=33856965 atc=33857053 tb=17060 sb=17060 eds=4800000 abps=4 sf=1
6032008-10-04 16:53:05.363 GetAudiotime now=1223164385.363419, set=1223164385.362010, ret=1, audt=33856965 sf=1
6042008-10-04 16:53:05.363 NVP: A/V Divergence: -1.6307, Rate: -0.235629, Warpfactor: 1.004, warpfactor_avg: 0.999999
6052008-10-04 16:53:05.365 SetAudiotime set=1223164385.365887, audt=33856969 atc=33857053 tb=16316 sb=16316 eds=4800000 abps=4 sf=1
6062008-10-04 16:53:05.367 AFD: video timecode 3047168492 3047168492 33857427 33857411
6072008-10-04 16:53:05.368 SetAudiotime set=1223164385.368050, audt=33856971 atc=33857053 tb=15900 sb=15900 eds=4800000 abps=4 sf=1
6082008-10-04 16:53:05.370 SetAudiotime set=1223164385.370135, audt=33856973 atc=33857053 tb=15500 sb=15500 eds=4800000 abps=4 sf=1
6092008-10-04 16:53:05.372 SetAudiotime set=1223164385.372211, audt=33856975 atc=33857053 tb=15100 sb=15100 eds=4800000 abps=4 sf=1
6102008-10-04 16:53:05.374 SetAudiotime set=1223164385.374286, audt=33856977 atc=33857053 tb=14704 sb=14704 eds=4800000 abps=4 sf=1
6112008-10-04 16:53:05.376 SetAudiotime set=1223164385.376366, audt=33856979 atc=33857053 tb=14304 sb=14304 eds=4800000 abps=4 sf=1
6122008-10-04 16:53:05.377 GetAudiotime now=1223164385.377326, set=1223164385.376366, ret=0, audt=33856979 sf=1
6132008-10-04 16:53:05.377 NVP: A/V Divergence: -1.76245, Rate: -0.131751, Warpfactor: 1.0035, warpfactor_avg: 0.999999
6142008-10-04 16:53:05.380 SetAudiotime set=1223164385.380146, audt=33856983 atc=33857053 tb=13576 sb=13576 eds=4800000 abps=4 sf=1
6152008-10-04 16:53:05.382 SetAudiotime set=1223164385.382316, audt=33856985 atc=33857053 tb=13160 sb=13160 eds=4800000 abps=4 sf=1
6162008-10-04 16:53:05.382 AFD: video timecode 3047169994 3047169994 33857444 33857427
6172008-10-04 16:53:05.384 SetAudiotime set=1223164385.384472, audt=33856987 atc=33857053 tb=12748 sb=12748 eds=4800000 abps=4 sf=1
6182008-10-04 16:53:05.386 SetAudiotime set=1223164385.386579, audt=33856989 atc=33857053 tb=12340 sb=12340 eds=4800000 abps=4 sf=1
6192008-10-04 16:53:05.388 SetAudiotime set=1223164385.388654, audt=33856991 atc=33857053 tb=11944 sb=11944 eds=4800000 abps=4 sf=1
6202008-10-04 16:53:05.390 SetAudiotime set=1223164385.390731, audt=33856993 atc=33857053 tb=11544 sb=11544 eds=4800000 abps=4 sf=1
6212008-10-04 16:53:05.392 SetAudiotime set=1223164385.392843, audt=33856995 atc=33857053 tb=11140 sb=11140 eds=4800000 abps=4 sf=1
6222008-10-04 16:53:05.394 GetAudiotime now=1223164385.394035, set=1223164385.392843, ret=1, audt=33856995 sf=1
6232008-10-04 16:53:05.394 NVP: A/V Divergence: -1.8613, Rate: -0.0988431, Warpfactor: 1.003, warpfactor_avg: 0.999999
6242008-10-04 16:53:05.397 SetAudiotime set=1223164385.397502, audt=33857000 atc=33857053 tb=10244 sb=10244 eds=4800000 abps=4 sf=1
6252008-10-04 16:53:05.398 AFD: video timecode 3047176000 3047171495 33857461 0
6262008-10-04 16:53:05.399 SetAudiotime set=1223164385.399652, audt=33857002 atc=33857053 tb=9832 sb=9832 eds=4800000 abps=4 sf=1
6272008-10-04 16:53:05.401 SetAudiotime set=1223164385.401740, audt=33857004 atc=33857053 tb=9432 sb=9432 eds=4800000 abps=4 sf=1
6282008-10-04 16:53:05.403 SetAudiotime set=1223164385.403815, audt=33857006 atc=33857053 tb=9032 sb=9032 eds=4800000 abps=4 sf=1
6292008-10-04 16:53:05.405 SetAudiotime set=1223164385.405891, audt=33857009 atc=33857053 tb=8632 sb=8632 eds=4800000 abps=4 sf=1
6302008-10-04 16:53:05.408 SetAudiotime set=1223164385.408189, audt=33857011 atc=33857053 tb=8192 sb=8192 eds=4800000 abps=4 sf=1
6312008-10-04 16:53:05.410 SetAudiotime set=1223164385.410283, audt=33857013 atc=33857053 tb=7792 sb=7792 eds=4800000 abps=4 sf=1
6322008-10-04 16:53:05.410 GetAudiotime now=1223164385.410947, set=1223164385.410283, ret=0, audt=33857013 sf=1
6332008-10-04 16:53:05.411 NVP: A/V Divergence: -1.93544, Rate: -0.0741473, Warpfactor: 1.00249, warpfactor_avg: 0.999999
6342008-10-04 16:53:05.413 SetAudiotime set=1223164385.413197, audt=33857016 atc=33857053 tb=7232 sb=7232 eds=4800000 abps=4 sf=1
6352008-10-04 16:53:05.414 AFD: video timecode 3047172997 3047172997 33857477 33857461
6362008-10-04 16:53:05.415 SetAudiotime set=1223164385.415363, audt=33857018 atc=33857053 tb=6816 sb=6816 eds=4800000 abps=4 sf=1
6372008-10-04 16:53:05.417 SetAudiotime set=1223164385.417457, audt=33857020 atc=33857053 tb=6412 sb=6412 eds=4800000 abps=4 sf=1
6382008-10-04 16:53:05.419 SetAudiotime set=1223164385.419542, audt=33857022 atc=33857053 tb=6012 sb=6012 eds=4800000 abps=4 sf=1
6392008-10-04 16:53:05.421 SetAudiotime set=1223164385.421615, audt=33857024 atc=33857053 tb=5616 sb=5616 eds=4800000 abps=4 sf=1
6402008-10-04 16:53:05.423 SetAudiotime set=1223164385.423688, audt=33857026 atc=33857053 tb=5216 sb=5216 eds=4800000 abps=4 sf=1
6412008-10-04 16:53:05.425 SetAudiotime set=1223164385.425838, audt=33857028 atc=33857053 tb=4804 sb=4804 eds=4800000 abps=4 sf=1
6422008-10-04 16:53:05.427 GetAudiotime now=1223164385.427391, set=1223164385.425838, ret=1, audt=33857028 sf=1
6432008-10-04 16:53:05.427 NVP: A/V Divergence: -1.99101, Rate: -0.0555655, Warpfactor: 1.00199, warpfactor_avg: 0.999999
6442008-10-04 16:53:05.429 SetAudiotime set=1223164385.429948, audt=33857033 atc=33857053 tb=4016 sb=4016 eds=4800000 abps=4 sf=1
6452008-10-04 16:53:05.430 AFD: video timecode 3047174498 3047174498 33857494 33857477
6462008-10-04 16:53:05.432 SetAudiotime set=1223164385.432124, audt=33857035 atc=33857053 tb=3596 sb=3596 eds=4800000 abps=4 sf=1
6472008-10-04 16:53:05.434 SetAudiotime set=1223164385.434203, audt=33857037 atc=33857053 tb=3196 sb=3196 eds=4800000 abps=4 sf=1
6482008-10-04 16:53:05.436 SetAudiotime set=1223164385.436278, audt=33857039 atc=33857053 tb=2800 sb=2800 eds=4800000 abps=4 sf=1
6492008-10-04 16:53:05.438 SetAudiotime set=1223164385.438353, audt=33857041 atc=33857053 tb=2400 sb=2400 eds=4800000 abps=4 sf=1
6502008-10-04 16:53:05.440 SetAudiotime set=1223164385.440509, audt=33857043 atc=33857053 tb=1988 sb=1988 eds=4800000 abps=4 sf=1
6512008-10-04 16:53:05.442 SetAudiotime set=1223164385.442692, audt=33857045 atc=33857053 tb=1568 sb=1568 eds=4800000 abps=4 sf=1
6522008-10-04 16:53:05.444 GetAudiotime now=1223164385.444011, set=1223164385.442692, ret=1, audt=33857045 sf=1
6532008-10-04 16:53:05.444 NVP: A/V Divergence: -2.03273, Rate: -0.0417191, Warpfactor: 1.00149, warpfactor_avg: 0.999999
6542008-10-04 16:53:05.446 SetAudiotime set=1223164385.446928, audt=33857050 atc=33857053 tb=752 sb=752 eds=4800000 abps=4 sf=1
6552008-10-04 16:53:05.448 AFD: video timecode 3047180504 3047176000 33857511 33857494
6562008-10-04 16:53:05.449 SetAudiotime set=1223164385.449090, audt=33857052 atc=33857053 tb=340 sb=340 eds=4800000 abps=4 sf=1
6572008-10-04 16:53:05.451 SetAudiotime set=1223164385.451182, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6582008-10-04 16:53:05.453 SetAudiotime set=1223164385.453249, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6592008-10-04 16:53:05.455 SetAudiotime set=1223164385.455321, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6602008-10-04 16:53:05.457 SetAudiotime set=1223164385.457391, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6612008-10-04 16:53:05.459 SetAudiotime set=1223164385.459496, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6622008-10-04 16:53:05.460 GetAudiotime now=1223164385.460475, set=1223164385.459496, ret=0, audt=33857053 sf=1
6632008-10-04 16:53:05.460 NVP: A/V Divergence: -1.91416, Rate: 0.118564, Warpfactor: 1.00099, warpfactor_avg: 0.999999
6642008-10-04 16:53:05.462 SetAudiotime set=1223164385.462511, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6652008-10-04 16:53:05.462 AFD: video timecode 3047177501 3047177501 33857527 33857511
6662008-10-04 16:53:05.464 SetAudiotime set=1223164385.464668, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6672008-10-04 16:53:05.466 SetAudiotime set=1223164385.466736, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6682008-10-04 16:53:05.468 SetAudiotime set=1223164385.468802, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6692008-10-04 16:53:05.470 SetAudiotime set=1223164385.470873, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6702008-10-04 16:53:05.472 SetAudiotime set=1223164385.472944, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6712008-10-04 16:53:05.475 SetAudiotime set=1223164385.475016, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6722008-10-04 16:53:05.477 GetAudiotime now=1223164385.477112, set=1223164385.475016, ret=2, audt=33857053 sf=1
6732008-10-04 16:53:05.477 NVP: A/V Divergence: -1.61542, Rate: 0.298747, Warpfactor: 1.00049, warpfactor_avg: 0.999999
6742008-10-04 16:53:05.477 SetAudiotime set=1223164385.477288, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6752008-10-04 16:53:05.479 SetAudiotime set=1223164385.479534, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6762008-10-04 16:53:05.479 AFD: video timecode 3047179003 3047179003 33857544 33857527
6772008-10-04 16:53:05.481 SetAudiotime set=1223164385.481687, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6782008-10-04 16:53:05.483 SetAudiotime set=1223164385.483763, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6792008-10-04 16:53:05.485 SetAudiotime set=1223164385.485838, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6802008-10-04 16:53:05.487 SetAudiotime set=1223164385.487911, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6812008-10-04 16:53:05.490 SetAudiotime set=1223164385.489999, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6822008-10-04 16:53:05.492 SetAudiotime set=1223164385.492085, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6832008-10-04 16:53:05.493 GetAudiotime now=1223164385.493921, set=1223164385.492085, ret=1, audt=33857053 sf=1
6842008-10-04 16:53:05.494 NVP: A/V Divergence: -1.12162, Rate: 0.493796, Warpfactor: 0.99999, warpfactor_avg: 0.999999
6852008-10-04 16:53:05.497 SetAudiotime set=1223164385.497556, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6862008-10-04 16:53:05.498 AFD: video timecode 3047185009 3047180504 33857561 33857544
6872008-10-04 16:53:05.499 SetAudiotime set=1223164385.499724, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6882008-10-04 16:53:05.501 SetAudiotime set=1223164385.501859, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6892008-10-04 16:53:05.503 SetAudiotime set=1223164385.503956, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6902008-10-04 16:53:05.506 SetAudiotime set=1223164385.506034, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6912008-10-04 16:53:05.508 SetAudiotime set=1223164385.508117, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6922008-10-04 16:53:05.510 SetAudiotime set=1223164385.510195, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6932008-10-04 16:53:05.510 GetAudiotime now=1223164385.510477, set=1223164385.510195, ret=0, audt=33857053 sf=1
6942008-10-04 16:53:05.510 NVP: A/V Divergence: -0.481568, Rate: 0.640053, Warpfactor: 1.00049, warpfactor_avg: 0.999999
6952008-10-04 16:53:05.513 SetAudiotime set=1223164385.513257, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6962008-10-04 16:53:05.513 AFD: video timecode 3047182006 3047182006 33857577 33857561
6972008-10-04 16:53:05.515 SetAudiotime set=1223164385.515423, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6982008-10-04 16:53:05.517 SetAudiotime set=1223164385.517532, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
6992008-10-04 16:53:05.519 SetAudiotime set=1223164385.519618, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7002008-10-04 16:53:05.521 SetAudiotime set=1223164385.521687, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7012008-10-04 16:53:05.523 SetAudiotime set=1223164385.523813, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7022008-10-04 16:53:05.525 SetAudiotime set=1223164385.525887, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7032008-10-04 16:53:05.527 GetAudiotime now=1223164385.527125, set=1223164385.525887, ret=1, audt=33857053 sf=1
7042008-10-04 16:53:05.527 NVP: A/V Divergence: 0.238207, Rate: 0.719775, Warpfactor: 1.00099, warpfactor_avg: 0.999999
7052008-10-04 16:53:05.529 SetAudiotime set=1223164385.529532, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7062008-10-04 16:53:05.530 AFD: video timecode 3047183507 3047183507 33857594 33857577
7072008-10-04 16:53:05.531 SetAudiotime set=1223164385.531685, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7082008-10-04 16:53:05.533 SetAudiotime set=1223164385.533759, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7092008-10-04 16:53:05.535 SetAudiotime set=1223164385.535831, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7102008-10-04 16:53:05.537 SetAudiotime set=1223164385.537903, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7112008-10-04 16:53:05.539 SetAudiotime set=1223164385.539974, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7122008-10-04 16:53:05.542 SetAudiotime set=1223164385.542047, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7132008-10-04 16:53:05.543 GetAudiotime now=1223164385.543892, set=1223164385.542047, ret=1, audt=33857053 sf=1
7142008-10-04 16:53:05.544 NVP: A/V Divergence: 1.0178, Rate: 0.779596, Warpfactor: 1.00149, warpfactor_avg: 0.999999
7152008-10-04 16:53:05.547 SetAudiotime set=1223164385.547561, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7162008-10-04 16:53:05.547 AFD: video timecode 3047189513 3047185009 33857611 33857594
7172008-10-04 16:53:05.549 SetAudiotime set=1223164385.549720, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7182008-10-04 16:53:05.551 SetAudiotime set=1223164385.551804, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7192008-10-04 16:53:05.553 SetAudiotime set=1223164385.553890, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7202008-10-04 16:53:05.555 SetAudiotime set=1223164385.555965, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7212008-10-04 16:53:05.558 SetAudiotime set=1223164385.558041, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7222008-10-04 16:53:05.560 SetAudiotime set=1223164385.560147, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7232008-10-04 16:53:05.562 SetAudiotime set=1223164385.562298, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7242008-10-04 16:53:05.563 GetAudiotime now=1223164385.563420, set=1223164385.562298, ret=1, audt=33857053 sf=1
7252008-10-04 16:53:05.563 NVP: A/V Divergence: 1.85728, Rate: 0.839477, Warpfactor: 1.00199, warpfactor_avg: 0.999999
7262008-10-04 16:53:05.566 SetAudiotime set=1223164385.566598, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7272008-10-04 16:53:05.566 AFD: video timecode 3047186510 3047186510 33857627 33857611
7282008-10-04 16:53:05.568 SetAudiotime set=1223164385.568765, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7292008-10-04 16:53:05.570 SetAudiotime set=1223164385.570851, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7302008-10-04 16:53:05.572 SetAudiotime set=1223164385.572929, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7312008-10-04 16:53:05.575 SetAudiotime set=1223164385.575025, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7322008-10-04 16:53:05.577 SetAudiotime set=1223164385.577234, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7332008-10-04 16:53:05.577 GetAudiotime now=1223164385.577416, set=1223164385.577234, ret=0, audt=33857053 sf=1
7342008-10-04 16:53:05.577 NVP: A/V Divergence: 2.75658, Rate: 0.899299, Warpfactor: 1.00249, warpfactor_avg: 0.999999
7352008-10-04 16:53:05.580 SetAudiotime set=1223164385.580028, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7362008-10-04 16:53:05.580 AFD: video timecode 3047188012 3047188012 33857644 33857627
7372008-10-04 16:53:05.582 SetAudiotime set=1223164385.582218, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7382008-10-04 16:53:05.584 SetAudiotime set=1223164385.584308, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7392008-10-04 16:53:05.586 SetAudiotime set=1223164385.586393, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7402008-10-04 16:53:05.588 SetAudiotime set=1223164385.588473, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7412008-10-04 16:53:05.590 SetAudiotime set=1223164385.590583, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7422008-10-04 16:53:05.592 SetAudiotime set=1223164385.592734, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7432008-10-04 16:53:05.593 GetAudiotime now=1223164385.593970, set=1223164385.592734, ret=1, audt=33857053 sf=1
7442008-10-04 16:53:05.594 NVP: A/V Divergence: 3.65588, Rate: 0.899299, Warpfactor: 1.00299, warpfactor_avg: 0.999999
7452008-10-04 16:53:05.598 SetAudiotime set=1223164385.598094, audt=33857053 atc=33857053 tb=0 sb=0 eds=4800000 abps=4 sf=1
7462008-10-04 16:53:05.598 AFD: video timecode 3047194018 3047189513 33857661 33857644
7472008-10-04 16:53:05.598 AFD: audio timecode 3047134819 3047134819 33857053 33857085
7482008-10-04 16:53:05.598 GetAudiotime now=1223164385.598799, set=1223164385.598094, ret=0, audt=33857053 sf=1
7492008-10-04 16:53:05.598 AO: _AddSamples samples=1536 bytes=6144, used=1, free=1535999, timecode=33857053 needsupmix 0
7502008-10-04 16:53:05.599 AFD: audio timecode 3047137699 3047137699 33857085 33857117
7512008-10-04 16:53:05.599 GetAudiotime now=1223164385.599332, set=1223164385.598094, ret=1, audt=33857053 sf=1
7522008-10-04 16:53:05.599 AO: _AddSamples samples=1536 bytes=6144, used=6145, free=1529855, timecode=33857085 needsupmix 0
7532008-10-04 16:53:05.599 AFD: audio timecode 3047140579 3047140579 33857117 33857149
7542008-10-04 16:53:05.599 GetAudiotime now=1223164385.599926, set=1223164385.598094, ret=1, audt=33857053 sf=1
7552008-10-04 16:53:05.599 AO: _AddSamples samples=1536 bytes=6144, used=12289, free=1523711, timecode=33857117 needsupmix 0
7562008-10-04 16:53:05.600 AFD: audio timecode 3047143459 3047143459 33857149 33857181
7572008-10-04 16:53:05.600 GetAudiotime now=1223164385.600733, set=1223164385.598094, ret=2, audt=33857053 sf=1
7582008-10-04 16:53:05.600 AO: _AddSamples samples=1536 bytes=6144, used=18433, free=1517567, timecode=33857149 needsupmix 0
7592008-10-04 16:53:05.601 AFD: audio timecode 3047146339 3047146339 33857181 33857213
7602008-10-04 16:53:05.601 GetAudiotime now=1223164385.601390, set=1223164385.598094, ret=3, audt=33857053 sf=1
7612008-10-04 16:53:05.601 AO: _AddSamples samples=1536 bytes=6144, used=24577, free=1511423, timecode=33857181 needsupmix 0
7622008-10-04 16:53:05.601 AFD: audio timecode 3047149219 3047149219 33857213 33857245
7632008-10-04 16:53:05.601 GetAudiotime now=1223164385.601886, set=1223164385.598094, ret=3, audt=33857053 sf=1
7642008-10-04 16:53:05.601 AO: _AddSamples samples=1536 bytes=6144, used=30721, free=1505279, timecode=33857213 needsupmix 0
7652008-10-04 16:53:05.602 AFD: audio timecode 3047152099 3047152099 33857245 33857277
7662008-10-04 16:53:05.602 GetAudiotime now=1223164385.602526, set=1223164385.598094, ret=4, audt=33857053 sf=1
7672008-10-04 16:53:05.602 AO: _AddSamples samples=1536 bytes=6144, used=36865, free=1499135, timecode=33857245 needsupmix 0
7682008-10-04 16:53:05.603 AFD: audio timecode 3047154979 3047154979 33857277 33857309
7692008-10-04 16:53:05.603 SetAudiotime set=1223164385.603457, audt=33857053 atc=33857277 tb=43008 sb=0 eds=4800000 abps=4 sf=1
7702008-10-04 16:53:05.603 GetAudiotime now=1223164385.603588, set=1223164385.603457, ret=0, audt=33857053 sf=1
7712008-10-04 16:53:05.603 AO: Broadcasting free space avail
7722008-10-04 16:53:05.603 GetAudiotime now=1223164385.603647, set=1223164385.603457, ret=0, audt=33857053 sf=1
7732008-10-04 16:53:05.603 WriteAudio: Preparing 6144 bytes (1536 frames)
7742008-10-04 16:53:05.603 AO: _AddSamples samples=1536 bytes=6144, used=36865, free=1499135, timecode=33857277 needsupmix 0
7752008-10-04 16:53:05.603 WriteAudio: buffer underrun
7762008-10-04 16:53:05.604 AFD: audio timecode 3047157859 3047157859 33857309 33857341
7772008-10-04 16:53:05.604 GetAudiotime now=1223164385.604512, set=1223164385.603457, ret=1, audt=33857053 sf=1
7782008-10-04 16:53:05.604 AO: _AddSamples samples=1536 bytes=6144, used=43009, free=1492991, timecode=33857309 needsupmix 0
7792008-10-04 16:53:05.605 AFD: audio timecode 3047160739 3047160739 33857341 33857373
7802008-10-04 16:53:05.605 GetAudiotime now=1223164385.605175, set=1223164385.603457, ret=1, audt=33857053 sf=1
7812008-10-04 16:53:05.605 AO: _AddSamples samples=1536 bytes=6144, used=49153, free=1486847, timecode=33857341 needsupmix 0
7822008-10-04 16:53:05.605 SetAudiotime set=1223164385.605290, audt=33857085 atc=33857373 tb=55296 sb=0 eds=4800000 abps=4 sf=1
7832008-10-04 16:53:05.605 GetAudiotime now=1223164385.605358, set=1223164385.605290, ret=0, audt=33857085 sf=1
7842008-10-04 16:53:05.605 AO: Broadcasting free space avail
7852008-10-04 16:53:05.605 WriteAudio: Preparing 6144 bytes (1536 frames)
7862008-10-04 16:53:05.605 SetAudiotime set=1223164385.605484, audt=33857117 atc=33857373 tb=49152 sb=0 eds=4800000 abps=4 sf=1