Ticket #9930: mythtv.log---0.24.1-Terratec

File mythtv.log---0.24.1-Terratec, 22.9 KB (added by mac <mac20xx@…>, 9 years ago)
Line 
12011-07-22 10:32:43.768 mythfrontend version:  [v0.24.1] www.mythtv.org
22011-07-22 10:32:43.769 Using runtime prefix = /usr
32011-07-22 10:32:43.769 Using configuration directory = /mnt/homes/mythtv---wz/.mythtv
42011-07-22 10:32:43.772 ThreadPool:HTTP: Initial 1, Max 25, Timeout 60000
52011-07-22 10:32:44.768 Using localhost value of wohnzimmer
62011-07-22 10:32:44.769 Testing network connectivity to 'server.home.xx'
72011-07-22 10:32:44.907 New DB connection, total: 1
82011-07-22 10:32:44.912 Connected to database 'mythconverg' at host: server.home.xx
92011-07-22 10:32:44.981 Closing DB connection named 'DBManager0'
102011-07-22 10:32:44.983 Connected to database 'mythconverg' at host: server.home.xx
112011-07-22 10:32:44.987 Current locale DE_DE
122011-07-22 10:32:44.988 Reading locale defaults from /usr/share/mythtv//locales/de_de.xml
132011-07-22 10:32:45.176 ScreenSaverX11Private: Gnome screen saver support enabled
142011-07-22 10:32:45.178 DPMS is disabled.
152011-07-22 10:32:45.215 Desktop video mode: 1280x720 60.073 Hz
162011-07-22 10:32:45.256 Enabled verbose msgs:  important general audio timestamp
172011-07-22 10:32:45.263 Loading de translation for module mythfrontend
182011-07-22 10:32:45.287 LIRC: Successfully initialized '/dev/lircd' using '/mnt/homes/mythtv---wz/.lircrc' config
192011-07-22 10:32:45.288 JoystickMenuThread: Joystick disabled - Failed to read /mnt/homes/mythtv---wz/.mythtv/joystickmenurc
202011-07-22 10:32:45.356 Using Frameless Window
212011-07-22 10:32:45.798 Using the OpenGL painter
222011-07-22 10:32:45.957 OpenGL: OpenGL vendor  : NVIDIA Corporation
232011-07-22 10:32:45.958 OpenGL: OpenGL renderer: GeForce4 Ti 4200/AGP/SSE/3DNOW!
242011-07-22 10:32:45.958 OpenGL: OpenGL version : 1.5.8 NVIDIA 96.43.18
252011-07-22 10:32:45.958 OpenGL: Max texture size: 4096 x 4096
262011-07-22 10:32:45.958 OpenGL: Max texture units: 4
272011-07-22 10:32:45.958 OpenGL: Direct rendering: Yes
282011-07-22 10:32:45.958 OpenGL: Initialised MythRenderOpenGL
292011-07-22 10:32:46.643 Current MythTV Schema Version (DBSchemaVer): 1264
302011-07-22 10:32:46.719 New DB connection, total: 2
312011-07-22 10:32:46.723 Connected to database 'mythconverg' at host: server.home.xx
322011-07-22 10:32:47.179 Pulse: PulseAudio not running
332011-07-22 10:32:47.196 AO: SRC quality = disabled
342011-07-22 10:32:47.200 AO: Sample rate 8000 is supported
352011-07-22 10:32:47.200 AO: Sample rate 11025 is supported
362011-07-22 10:32:47.200 AO: Sample rate 16000 is supported
372011-07-22 10:32:47.200 AO: Sample rate 22050 is supported
382011-07-22 10:32:47.200 AO: Sample rate 32000 is supported
392011-07-22 10:32:47.200 AO: Sample rate 44100 is supported
402011-07-22 10:32:47.200 AO: Sample rate 48000 is supported
412011-07-22 10:32:47.201 AO: Sample rate 64000 is supported
422011-07-22 10:32:47.201 AO: Sample rate 88200 is supported
432011-07-22 10:32:47.201 AO: Sample rate 96000 is supported
442011-07-22 10:32:47.201 AO: 1 channel(s) are supported
452011-07-22 10:32:47.201 AO: 2 channel(s) are supported
462011-07-22 10:32:47.201 AO: 3 channel(s) are supported
472011-07-22 10:32:47.201 AO: 4 channel(s) are supported
482011-07-22 10:32:47.201 AO: 5 channel(s) are supported
492011-07-22 10:32:47.201 AO: 6 channel(s) are supported
502011-07-22 10:32:47.201 AO: 7 channel(s) are supported
512011-07-22 10:32:47.201 AO: 8 channel(s) are supported
522011-07-22 10:32:47.232 AO: Killing AudioOutputDSP
532011-07-22 10:32:47.232 Found ALSA:iec958:CARD=EWX2496,DEV=0 (ALSA:iec958:CARD=EWX2496,DEV=0
54Device supports up to 7.1 (digital output, AC3,DTS,multi-channels LPCM))
552011-07-22 10:32:47.508 Registering Internal as a media playback plugin.
562011-07-22 10:32:47.640 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
57                        eno: Datei oder Verzeichnis nicht gefunden (2)
582011-07-22 10:32:47.680 MMUnix::AddDevice() Error: failed to stat /dev/power,
59                        eno: Datei oder Verzeichnis nicht gefunden (2)
602011-07-22 10:32:47.731 MMUnix::AddDevice() Error: failed to stat /dev/trace,
61                        eno: Datei oder Verzeichnis nicht gefunden (2)
622011-07-22 10:32:47.770 MonitorRegisterExtensions(0x100, gif,jpg,png)
632011-07-22 10:32:47.771 Loading de translation for module mythgallery
642011-07-22 10:32:47.843 Current MythMusic Schema Version (MusicDBSchemaVer): 1017
652011-07-22 10:32:47.935 MonitorRegisterExtensions(0x40, mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
662011-07-22 10:32:47.950 Loading de translation for module mythmusic
672011-07-22 10:32:47.967 Current MythVideo Schema Version (mythvideo.DBSchemaVer): 1038
682011-07-22 10:32:48.010 Loading de translation for module mythvideo
692011-07-22 10:32:48.032 Starting update of BBC-Current-XML
702011-07-22 10:32:48.033 BBC-Current-XML recently updated, skipping.
712011-07-22 10:32:48.035 Starting update of BBC-3day-XML
722011-07-22 10:32:48.036 BBC-3day-XML recently updated, skipping.
732011-07-22 10:32:48.039 Loading de translation for module mythweather
742011-07-22 10:32:48.770 Found mainmenu.xml for theme 'MythCenter-wide---024'
752011-07-22 10:32:49.025 MythCoreContext: Connecting to backend server: 192.168.33.2:6543 (try 1 of 5)
762011-07-22 10:32:49.027 Using protocol version 63
772011-07-22 10:32:57.845 New DB connection, total: 3
782011-07-22 10:32:57.848 Connected to database 'mythconverg' at host: server.home.xx
792011-07-22 10:32:58.081 XMLParse: LoadTheme using '/usr/share/mythtv/themes/default-wide/music-ui.xml'
802011-07-22 10:33:09.202 Pulse: PulseAudio not running
812011-07-22 10:33:09.216 AO: SRC quality = disabled
822011-07-22 10:33:09.219 AO: Sample rate 8000 is supported
832011-07-22 10:33:09.219 AO: Sample rate 11025 is supported
842011-07-22 10:33:09.219 AO: Sample rate 16000 is supported
852011-07-22 10:33:09.219 AO: Sample rate 22050 is supported
862011-07-22 10:33:09.220 AO: Sample rate 32000 is supported
872011-07-22 10:33:09.220 AO: Sample rate 44100 is supported
882011-07-22 10:33:09.220 AO: Sample rate 48000 is supported
892011-07-22 10:33:09.220 AO: Sample rate 64000 is supported
902011-07-22 10:33:09.220 AO: Sample rate 88200 is supported
912011-07-22 10:33:09.220 AO: Sample rate 96000 is supported
922011-07-22 10:33:09.220 AO: 1 channel(s) are supported
932011-07-22 10:33:09.220 AO: 2 channel(s) are supported
942011-07-22 10:33:09.220 AO: 3 channel(s) are supported
952011-07-22 10:33:09.220 AO: 4 channel(s) are supported
962011-07-22 10:33:09.220 AO: 5 channel(s) are supported
972011-07-22 10:33:09.221 AO: 6 channel(s) are supported
982011-07-22 10:33:09.221 AO: 7 channel(s) are supported
992011-07-22 10:33:09.221 AO: 8 channel(s) are supported
1002011-07-22 10:33:09.248 AO: Killing AudioOutputDSP
1012011-07-22 10:33:09.249 AO: Original codec was NONE, signed 16 bit, 44 kHz, 2 channels
1022011-07-22 10:33:09.249 AO: enc(0), passthru(0), canAC3(0), canDTS(0), canLPCM(0), configured_channels(2), 2 channels supported(1)
1032011-07-22 10:33:09.250 AO: Opening audio device 'iec958:CARD=EWX2496,DEV=0' ch 2(2) sr 44100 sf signed 16 bit reenc 0
1042011-07-22 10:33:09.252 ALSA: SetParameters(format=2, channels=2, rate=44100, buffer_time=500000, period_time=4)
1052011-07-22 10:33:09.253 ALSA: Buffer size range from 2 to 6553
1062011-07-22 10:33:09.253 ALSA: Period size range from 2 to 3276
1072011-07-22 10:33:09.253 ALSA: Requested 500000us got 148594 buffer time
1082011-07-22 10:33:09.253 ALSA: Hardware audio buffer cur: 256 need: 896 max allowed: 256
1092011-07-22 10:33:09.253 ALSA, Error: Unable to sufficiently increase ALSA hardware buffer size - underruns are likely
1102011-07-22 10:33:09.255 ALSA: SetParameters(format=2, channels=2, rate=44100, buffer_time=500000, period_time=4)
1112011-07-22 10:33:09.256 ALSA: Buffer size range from 2 to 6553
1122011-07-22 10:33:09.256 ALSA: Period size range from 2 to 3276
1132011-07-22 10:33:09.256 ALSA: Buffer time = 148594 us
1142011-07-22 10:33:09.256 ALSA: Period time = 4 periods
1152011-07-22 10:33:09.261 ALSA: Buffer size = 6553 | Period size = 1311
1162011-07-22 10:33:09.261 ALSA: Period size changed from 1311 to 1304
1172011-07-22 10:33:09.262 AO: Audio fragment size: 2622
1182011-07-22 10:33:09.262 AO: Audio Stretch Factor: 1
1192011-07-22 10:33:09.262 AO: Ending Reconfigure()
1202011-07-22 10:33:09.264 AO: kickoffOutputAudioLoop: pid = 2018
1212011-07-22 10:33:09.264 AO: OutputAudioLoop: Play Event
1222011-07-22 10:33:09.489 AO: Pause 1
1232011-07-22 10:33:09.496 AO: OutputAudioLoop: audio paused
1242011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1252011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1262011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1272011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1282011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1292011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1302011-07-22 10:33:09.497 WriteAudio: Preparing 704 bytes (176 frames)
1312011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1322011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1332011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1342011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1352011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1362011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1372011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1382011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1392011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1402011-07-22 10:33:09.498 WriteAudio: Preparing 704 bytes (176 frames)
1412011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1422011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1432011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1442011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1452011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1462011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1472011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1482011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1492011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1502011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1512011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1522011-07-22 10:33:09.499 WriteAudio: Preparing 704 bytes (176 frames)
1532011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1542011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1552011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1562011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1572011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1582011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1592011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1602011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1612011-07-22 10:33:09.500 WriteAudio: Preparing 704 bytes (176 frames)
1622011-07-22 10:33:09.527 WriteAudio: Preparing 704 bytes (176 frames)
1632011-07-22 10:33:09.528 WriteAudio: Preparing 704 bytes (176 frames)
1642011-07-22 10:33:09.528 WriteAudio: Preparing 704 bytes (176 frames)
1652011-07-22 10:33:09.528 WriteAudio: Preparing 704 bytes (176 frames)
1662011-07-22 10:33:09.528 WriteAudio: Preparing 704 bytes (176 frames)
1672011-07-22 10:33:09.528 WriteAudio: Preparing 704 bytes (176 frames)
1682011-07-22 10:33:09.528 WriteAudio: Preparing 704 bytes (176 frames)
1692011-07-22 10:33:09.540 AO: Reconfigure(): No change -> exiting
1702011-07-22 10:33:09.548 AO: AddFrames frames=882, bytes=3528, used=1, free=3071999, timecode=-1 needsupmix=0
1712011-07-22 10:33:09.548 AO: SetAudiotime atc=0 tc=0 f=882 pfu=0 pfs=0
1722011-07-22 10:33:09.548 AO: AddFrames frames=882, bytes=3528, used=3529, free=3068471, timecode=-1 needsupmix=0
1732011-07-22 10:33:09.548 AO: SetAudiotime atc=20 tc=20 f=882 pfu=0 pfs=0
1742011-07-22 10:33:09.548 AO: AddFrames frames=882, bytes=3528, used=7057, free=3064943, timecode=-1 needsupmix=0
1752011-07-22 10:33:09.549 AO: SetAudiotime atc=40 tc=40 f=882 pfu=0 pfs=0
1762011-07-22 10:33:09.549 AO: AddFrames frames=882, bytes=3528, used=10585, free=3061415, timecode=-1 needsupmix=0
1772011-07-22 10:33:09.549 AO: Pause 0
1782011-07-22 10:33:09.549 AO: SetAudiotime atc=60 tc=60 f=882 pfu=0 pfs=0
1792011-07-22 10:33:09.549 AO: AddFrames frames=882, bytes=3528, used=14113, free=3057887, timecode=-1 needsupmix=0
1802011-07-22 10:33:09.549 AO: SetAudiotime atc=80 tc=80 f=882 pfu=0 pfs=0
1812011-07-22 10:33:09.549 AO: AddFrames frames=882, bytes=3528, used=17641, free=3054359, timecode=-1 needsupmix=0
1822011-07-22 10:33:09.549 AO: SetAudiotime atc=100 tc=100 f=882 pfu=0 pfs=0
1832011-07-22 10:33:09.549 AO: AddFrames frames=882, bytes=3528, used=21169, free=3050831, timecode=-1 needsupmix=0
1842011-07-22 10:33:09.549 AO: SetAudiotime atc=120 tc=120 f=882 pfu=0 pfs=0
1852011-07-22 10:33:09.549 AO: AddFrames frames=882, bytes=3528, used=24697, free=3047303, timecode=-1 needsupmix=0
1862011-07-22 10:33:09.550 AO: SetAudiotime atc=140 tc=140 f=882 pfu=0 pfs=0
1872011-07-22 10:33:09.550 AO: AddFrames frames=882, bytes=3528, used=28225, free=3043775, timecode=-1 needsupmix=0
1882011-07-22 10:33:09.550 AO: SetAudiotime atc=160 tc=160 f=882 pfu=0 pfs=0
1892011-07-22 10:33:09.551 AO: AddFrames frames=882, bytes=3528, used=31753, free=3040247, timecode=-1 needsupmix=0
1902011-07-22 10:33:09.551 AO: SetAudiotime atc=180 tc=180 f=882 pfu=0 pfs=0
1912011-07-22 10:33:09.551 AO: AddFrames frames=882, bytes=3528, used=35281, free=3036719, timecode=-1 needsupmix=0
1922011-07-22 10:33:09.551 AO: SetAudiotime atc=200 tc=200 f=882 pfu=0 pfs=0
1932011-07-22 10:33:09.551 AO: AddFrames frames=882, bytes=3528, used=38809, free=3033191, timecode=-1 needsupmix=0
1942011-07-22 10:33:09.552 AO: SetAudiotime atc=220 tc=220 f=882 pfu=0 pfs=0
1952011-07-22 10:33:09.552 AO: AddFrames frames=882, bytes=3528, used=42337, free=3029663, timecode=-1 needsupmix=0
1962011-07-22 10:33:09.552 AO: SetAudiotime atc=240 tc=240 f=882 pfu=0 pfs=0
1972011-07-22 10:33:09.552 AO: AddFrames frames=882, bytes=3528, used=45865, free=3026135, timecode=-1 needsupmix=0
1982011-07-22 10:33:09.552 AO: SetAudiotime atc=260 tc=260 f=882 pfu=0 pfs=0
1992011-07-22 10:33:09.552 AO: AddFrames frames=882, bytes=3528, used=49393, free=3022607, timecode=-1 needsupmix=0
2002011-07-22 10:33:09.552 AO: SetAudiotime atc=280 tc=280 f=882 pfu=0 pfs=0
2012011-07-22 10:33:09.552 AO: AddFrames frames=882, bytes=3528, used=52921, free=3019079, timecode=-1 needsupmix=0
2022011-07-22 10:33:09.552 AO: SetAudiotime atc=300 tc=300 f=882 pfu=0 pfs=0
2032011-07-22 10:33:09.552 AO: AddFrames frames=882, bytes=3528, used=56449, free=3015551, timecode=-1 needsupmix=0
2042011-07-22 10:33:09.552 AO: SetAudiotime atc=320 tc=320 f=882 pfu=0 pfs=0
2052011-07-22 10:33:09.552 AO: AddFrames frames=882, bytes=3528, used=59977, free=3012023, timecode=-1 needsupmix=0
2062011-07-22 10:33:09.553 AO: SetAudiotime atc=340 tc=340 f=882 pfu=0 pfs=0
2072011-07-22 10:33:09.554 AO: AddFrames frames=882, bytes=3528, used=63505, free=3008495, timecode=-1 needsupmix=0
2082011-07-22 10:33:09.557 AO: OutputAudioLoop: Play Event
2092011-07-22 10:33:09.557 AO: GetAudiotime audt=0 atc=340 mb=63504 sb=21112 tb=84616 sr=44100 obpf=4 bpf=4 sf=1 8461600000 479
2102011-07-22 10:33:09.558 AO: GetAudiotime audt=0 atc=340 mb=63504 sb=21080 tb=84584 sr=44100 obpf=4 bpf=4 sf=1 8458400000 479
2112011-07-22 10:33:09.558 ALSA, Error: ALSA: WriteAudio: data not aligned to alsa-frame size
2122011-07-22 10:33:09.558 ALSA, Error: ALSA:             size: 2622, size needed: 2616
2132011-07-22 10:33:09.558 WriteAudio: Preparing 2622 bytes (655 frames)
2142011-07-22 10:33:09.558 AO: GetAudiotime audt=0 atc=340 mb=60882 sb=23624 tb=84506 sr=44100 obpf=4 bpf=4 sf=1 8450600000 479
2152011-07-22 10:33:09.558 ALSA, Error: ALSA: WriteAudio: data not aligned to alsa-frame size
2162011-07-22 10:33:09.558 ALSA, Error: ALSA:             size: 2622, size needed: 2616
2172011-07-22 10:33:09.558 WriteAudio: Preparing 2622 bytes (655 frames)
2182011-07-22 10:33:09.558 AO: GetAudiotime audt=0 atc=340 mb=58260 sb=26184 tb=84444 sr=44100 obpf=4 bpf=4 sf=1 8444400000 478
2192011-07-22 10:33:09.559 ALSA, Error: ALSA: WriteAudio: data not aligned to alsa-frame size
2202011-07-22 10:33:09.559 ALSA, Error: ALSA:             size: 2622, size needed: 2616
2212011-07-22 10:33:09.559 WriteAudio: Preparing 2622 bytes (655 frames)
2222011-07-22 10:33:09.566 AO: SetAudiotime atc=360 tc=360 f=882 pfu=0 pfs=0
2232011-07-22 10:33:09.567 AO: AddFrames frames=882, bytes=3528, used=61789, free=3010211, timecode=-1 needsupmix=0
2242011-07-22 10:33:09.567 AO: SetAudiotime atc=380 tc=380 f=882 pfu=0 pfs=0
2252011-07-22 10:33:09.567 AO: AddFrames frames=882, bytes=3528, used=65317, free=3006683, timecode=-1 needsupmix=0
2262011-07-22 10:33:09.567 AO: SetAudiotime atc=400 tc=400 f=882 pfu=0 pfs=0
2272011-07-22 10:33:09.567 AO: AddFrames frames=882, bytes=3528, used=68845, free=3003155, timecode=-1 needsupmix=0
2282011-07-22 10:33:09.567 AO: SetAudiotime atc=420 tc=420 f=882 pfu=0 pfs=0
2292011-07-22 10:33:09.567 AO: AddFrames frames=882, bytes=3528, used=72373, free=2999627, timecode=-1 needsupmix=0
2302011-07-22 10:33:09.567 AO: SetAudiotime atc=440 tc=440 f=882 pfu=0 pfs=0
2312011-07-22 10:33:09.567 AO: AddFrames frames=882, bytes=3528, used=75901, free=2996099, timecode=-1 needsupmix=0
2322011-07-22 10:33:09.567 AO: SetAudiotime atc=460 tc=460 f=882 pfu=0 pfs=0
2332011-07-22 10:33:09.567 AO: AddFrames frames=882, bytes=3528, used=79429, free=2992571, timecode=-1 needsupmix=0
2342011-07-22 10:33:09.567 AO: SetAudiotime atc=480 tc=480 f=882 pfu=0 pfs=0
2352011-07-22 10:33:09.568 AO: AddFrames frames=882, bytes=3528, used=82957, free=2989043, timecode=-1 needsupmix=0
2362011-07-22 10:33:09.568 AO: SetAudiotime atc=500 tc=500 f=882 pfu=0 pfs=0
2372011-07-22 10:33:09.568 AO: AddFrames frames=882, bytes=3528, used=86485, free=2985515, timecode=-1 needsupmix=0
2382011-07-22 10:33:09.568 AO: SetAudiotime atc=520 tc=520 f=882 pfu=0 pfs=0
2392011-07-22 10:33:09.569 AO: AddFrames frames=882, bytes=3528, used=90013, free=2981987, timecode=-1 needsupmix=0
2402011-07-22 10:33:09.569 AO: SetAudiotime atc=540 tc=540 f=882 pfu=0 pfs=0
2412011-07-22 10:33:09.569 AO: AddFrames frames=882, bytes=3528, used=93541, free=2978459, timecode=-1 needsupmix=0
2422011-07-22 10:33:09.570 AO: SetAudiotime atc=560 tc=560 f=882 pfu=0 pfs=0
2432011-07-22 10:33:09.570 AO: AddFrames frames=882, bytes=3528, used=97069, free=2974931, timecode=-1 needsupmix=0
2442011-07-22 10:33:09.570 AO: SetAudiotime atc=580 tc=580 f=882 pfu=0 pfs=0
2452011-07-22 10:33:09.570 AO: AddFrames frames=882, bytes=3528, used=100597, free=2971403, timecode=-1 needsupmix=0
2462011-07-22 10:33:09.570 AO: SetAudiotime atc=600 tc=600 f=882 pfu=0 pfs=0
2472011-07-22 10:33:09.574 AO: AddFrames frames=882, bytes=3528, used=104125, free=2967875, timecode=-1 needsupmix=0
2482011-07-22 10:33:09.574 AO: SetAudiotime atc=620 tc=620 f=882 pfu=0 pfs=0
2492011-07-22 10:33:09.574 AO: AddFrames frames=882, bytes=3528, used=107653, free=2964347, timecode=-1 needsupmix=0
2502011-07-22 10:33:09.574 AO: SetAudiotime atc=640 tc=640 f=882 pfu=0 pfs=0
2512011-07-22 10:33:09.574 AO: AddFrames frames=882, bytes=3528, used=111181, free=2960819, timecode=-1 needsupmix=0
2522011-07-22 10:33:09.574 AO: SetAudiotime atc=660 tc=660 f=882 pfu=0 pfs=0
2532011-07-22 10:33:09.574 AO: AddFrames frames=882, bytes=3528, used=114709, free=2957291, timecode=-1 needsupmix=0
2542011-07-22 10:33:09.575 AO: SetAudiotime atc=680 tc=680 f=882 pfu=0 pfs=0
2552011-07-22 10:33:09.575 AO: AddFrames frames=882, bytes=3528, used=118237, free=2953763, timecode=-1 needsupmix=0
2562011-07-22 10:33:09.575 AO: SetAudiotime atc=700 tc=700 f=882 pfu=0 pfs=0
2572011-07-22 10:33:09.575 AO: AddFrames frames=882, bytes=3528, used=121765, free=2950235, timecode=-1 needsupmix=0
2582011-07-22 10:33:09.575 AO: SetAudiotime atc=720 tc=720 f=882 pfu=0 pfs=0
2592011-07-22 10:33:09.576 AO: AddFrames frames=882, bytes=3528, used=125293, free=2946707, timecode=-1 needsupmix=0
2602011-07-22 10:33:09.576 AO: SetAudiotime atc=740 tc=740 f=882 pfu=0 pfs=0
2612011-07-22 10:33:09.576 AO: AddFrames frames=882, bytes=3528, used=128821, free=2943179, timecode=-1 needsupmix=0
2622011-07-22 10:33:09.577 AO: SetAudiotime atc=760 tc=760 f=882 pfu=0 pfs=0
2632011-07-22 10:33:09.577 AO: AddFrames frames=882, bytes=3528, used=132349, free=2939651, timecode=-1 needsupmix=0
2642011-07-22 10:33:09.577 AO: SetAudiotime atc=780 tc=780 f=882 pfu=0 pfs=0
2652011-07-22 10:33:09.577 AO: AddFrames frames=882, bytes=3528, used=135877, free=2936123, timecode=-1 needsupmix=0
2662011-07-22 10:33:09.580 AO: SetAudiotime atc=800 tc=800 f=882 pfu=0 pfs=0
2672011-07-22 10:33:09.581 AO: AddFrames frames=882, bytes=3528, used=139405, free=2932595, timecode=-1 needsupmix=0
2682011-07-22 10:33:09.581 AO: SetAudiotime atc=820 tc=820 f=882 pfu=0 pfs=0
2692011-07-22 10:33:09.581 AO: AddFrames frames=882, bytes=3528, used=142933, free=2929067, timecode=-1 needsupmix=0
2702011-07-22 10:33:09.581 AO: SetAudiotime atc=840 tc=840 f=882 pfu=0 pfs=0
2712011-07-22 10:33:09.581 AO: AddFrames frames=882, bytes=3528, used=146461, free=2925539, timecode=-1 needsupmix=0
2722011-07-22 10:33:09.581 AO: SetAudiotime atc=860 tc=860 f=882 pfu=0 pfs=0
2732011-07-22 10:33:09.581 AO: AddFrames frames=882, bytes=3528, used=149989, free=2922011, timecode=-1 needsupmix=0
2742011-07-22 10:33:09.581 AO: SetAudiotime atc=880 tc=880 f=882 pfu=0 pfs=0
2752011-07-22 10:33:09.581 AO: AddFrames frames=882, bytes=3528, used=153517, free=2918483, timecode=-1 needsupmix=0
2762011-07-22 10:33:09.581 AO: SetAudiotime atc=900 tc=900 f=882 pfu=0 pfs=0
2772011-07-22 10:33:09.587 AO: AddFrames frames=882, bytes=3528, used=157045, free=2914955, timecode=-1 needsupmix=0
2782011-07-22 10:33:09.587 AO: SetAudiotime atc=920 tc=920 f=882 pfu=0 pfs=0
2792011-07-22 10:33:09.587 AO: AddFrames frames=882, bytes=3528, used=160573, free=2911427, timecode=-1 needsupmix=0
2802011-07-22 10:33:09.587 AO: SetAudiotime atc=940 tc=940 f=882 pfu=0 pfs=0
2812011-07-22 10:33:09.588 AO: AddFrames frames=882, bytes=3528, used=164101, free=2907899, timecode=-1 needsupmix=0
2822011-07-22 10:33:09.588 AO: SetAudiotime atc=960 tc=960 f=882 pfu=0 pfs=0
2832011-07-22 10:33:09.588 AO: AddFrames frames=882, bytes=3528, used=167629, free=2904371, timecode=-1 needsupmix=0
2842011-07-22 10:33:09.588 AO: SetAudiotime atc=980 tc=980 f=882 pfu=0 pfs=0
2852011-07-22 10:33:09.588 AO: GetAudiotime audt=0 atc=980 mb=171156 sb=20992 tb=192148 sr=44100 obpf=4 bpf=4 sf=1 19214800000 1089
2862011-07-22 10:33:09.617 AO: GetAudiotime audt=0 atc=980 mb=168534 sb=18540 tb=187074 sr=44100 obpf=4 bpf=4 sf=1 18707400000 1060
2872011-07-22 10:33:09.617 ALSA, Error: ALSA: WriteAudio: data not aligned to alsa-frame size
2882011-07-22 10:33:09.617 ALSA, Error: ALSA:             size: 2622, size needed: 2616
2892011-07-22 10:33:09.617 WriteAudio: Preparing 2622 bytes (655 frames)
2902011-07-22 10:33:09.617 AO: GetAudiotime audt=0 atc=980 mb=165912 sb=21060 tb=186972 sr=44100 obpf=4 bpf=4 sf=1 18697200000 1059
2912011-07-22 10:33:09.617 ALSA, Error: ALSA: WriteAudio: data not aligned to alsa-frame size
2922011-07-22 10:33:09.617 ALSA, Error: ALSA:             size: 2622, size needed: 2616
2932011-07-22 10:33:09.618 WriteAudio: Preparing 2622 bytes (655 frames)
2942011-07-22 10:33:09.618 AO: GetAudiotime audt=0 atc=980 mb=163290 sb=23620 tb=186910 sr=44100 obpf=4 bpf=4 sf=1 18691000000 1059
2952011-07-22 10:33:09.618 ALSA, Error: ALSA: WriteAudio: data not aligned to alsa-frame size
2962011-07-22 10:33:09.618 ALSA, Error: ALSA:             size: 2622, size needed: 2616
2972011-07-22 10:33:09.618 WriteAudio: Preparing 2622 bytes (655 frames)
2982011-07-22 10:33:09.618 AO: GetAudiotime audt=0 atc=980 mb=160668 sb=26168 tb=186836 sr=44100 obpf=4 bpf=4 sf=1 18683600000 1059
299