Ticket #287: debug-broken.txt

File debug-broken.txt, 89.6 KB (added by jung@…, 19 years ago)

debug txt-files, for easier reading

Line 
12005-08-31 22:09:26.087 Using runtime prefix = /usr
22005-08-31 22:09:26.104 New DB connection, total: 1
32005-08-31 22:09:26.107 Total desktop dim: 720x576, with 1 screen[s].
42005-08-31 22:09:26.109 Using screen 0, 720x576 at 0,0
52005-08-31 22:09:26.112 mythfrontend version: 0.19.20050712-1 www.mythtv.org
62005-08-31 22:09:26.113 Enabled verbose msgs :all
72005-08-31 22:09:26.247 max_width: 720 max_height: 576
82005-08-31 22:09:26.325 Total desktop dim: 720x576, with 1 screen[s].
92005-08-31 22:09:26.326 Using screen 0, 720x576 at 0,0
102005-08-31 22:09:26.328 Switching to square mode (blue)
112005-08-31 22:09:26.518 Registering Internal as a media playback plugin.
122005-08-31 22:09:26.534 Registering MythDVD DVD Media Handler as a media handler
132005-08-31 22:09:26.534 Registering MythDVD VCD Media Handler as a media handler
142005-08-31 22:09:26.873 Registering MythMusic Media Handler as a media handler
15SIP listening on IP Address 192.168.0.3:5060 NAT address 192.168.0.3
16SIP: Cannot register; proxy, username or password not set
172005-08-31 22:09:27.179 generating cache image for: /usr/share/mythtv/themes/default/blank.png
182005-08-31 22:09:27.470 Starting media monitor.
192005-08-31 22:09:28.567 New DB connection, total: 2
202005-08-31 22:09:28.610 Connecting to backend server: 127.0.0.1:6543 (try 1 of 5)
212005-08-31 22:09:28.610 write -> 14 21      ANN Playback vdrtv1 0
222005-08-31 22:09:28.627 read  <- 14 2       OK
232005-08-31 22:09:28.630 write -> 14 29      GET_NEXT_FREE_RECORDER[]:[]-1
242005-08-31 22:09:28.647 read  <- 14 24      1[]:[]127.0.0.1[]:[]6543
252005-08-31 22:09:28.648 write -> 16 21      ANN Playback vdrtv1 1
262005-08-31 22:09:28.648 Attempting to change from None to WatchingLiveTV
272005-08-31 22:09:28.649 write -> 17 21      MYTH_PROTO_VERSION 18
282005-08-31 22:09:28.664 read  <- 16 2       OK
292005-08-31 22:09:28.664 read  <- 17 13      ACCEPT[]:[]18
302005-08-31 22:09:28.664 Using protocol version 18
312005-08-31 22:09:28.664 write -> 17 21      ANN Playback vdrtv1 0
322005-08-31 22:09:28.680 read  <- 17 2       OK
332005-08-31 22:09:28.680 write -> 17 44      QUERY_RECORDER 1[]:[]SETUP_RING_BUFFER[]:[]0
342005-08-31 22:09:28.696 read  <- 17 92      ok[]:[]rbuf://127.0.0.1:6543/mnt/store/ringbuf1.nuv[]:[]2[]:[]-21...
352005-08-31 22:09:28.697 write -> 18 21      ANN Playback vdrtv1 0
362005-08-31 22:09:28.712 read  <- 18 2       OK
372005-08-31 22:09:28.712 write -> 19 23      ANN RingBuffer vdrtv1 1
382005-08-31 22:09:28.728 read  <- 19 2       OK
392005-08-31 22:09:28.728 write -> 17 33      QUERY_RECORDER 1[]:[]SPAWN_LIVETV
402005-08-31 22:09:28.760 read  <- 17 2       ok
412005-08-31 22:09:28.761 write -> 17 33      QUERY_RECORDER 1[]:[]IS_RECORDING
422005-08-31 22:09:28.776 read  <- 17 1       1
432005-08-31 22:09:28.777 write -> 17 33      QUERY_RECORDER 1[]:[]IS_RECORDING
442005-08-31 22:09:28.793 read  <- 17 1       1
452005-08-31 22:09:28.793 write -> 17 34      QUERY_RECORDER 1[]:[]GET_FRAMERATE
462005-08-31 22:09:28.809 read  <- 17 2       25
472005-08-31 22:09:28.823 write -> 17 37      QUERY_RECORDER 1[]:[]GET_PROGRAM_INFO
482005-08-31 22:09:28.833 read  <- 16 475     BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
492005-08-31 22:09:28.841 read  <- 17 103      []:[] []:[] []:[] []:[] []:[] []:[]Nelonen[]:[]none[]:[]4[]:[]10...
502005-08-31 22:09:28.841 Output filters for this channel are: ''
512005-08-31 22:09:28.842 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
522005-08-31 22:09:28.861 read  <- 18 6       128000
532005-08-31 22:09:28.861 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
542005-08-31 22:09:28.869 write -> 18 66      QUERY_RECORDER 1[]:[]SEEK_RINGBUF[]:[]0[]:[]0[]:[]0[]:[]0[]:[]2048
552005-08-31 22:09:28.885 read  <- 18 7       0[]:[]0
562005-08-31 22:09:28.889 read  <- 16 475     BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
572005-08-31 22:09:28.893 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
582005-08-31 22:09:28.902 read  <- 18 6       128000
592005-08-31 22:09:28.902 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
602005-08-31 22:09:28.913 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
612005-08-31 22:09:28.922 read  <- 18 6       128000
622005-08-31 22:09:28.922 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
632005-08-31 22:09:28.933 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
642005-08-31 22:09:28.946 read  <- 18 6       128000
652005-08-31 22:09:28.946 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
662005-08-31 22:09:28.957 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
672005-08-31 22:09:28.962 read  <- 16 475     BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
682005-08-31 22:09:28.966 read  <- 18 6       128000
692005-08-31 22:09:28.966 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
702005-08-31 22:09:28.977 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
712005-08-31 22:09:28.987 read  <- 18 6       128000
722005-08-31 22:09:28.987 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
732005-08-31 22:09:28.998 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
742005-08-31 22:09:29.006 read  <- 16 475     BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
752005-08-31 22:09:29.010 read  <- 18 6       128000
762005-08-31 22:09:29.010 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
772005-08-31 22:09:29.022 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
782005-08-31 22:09:29.034 read  <- 18 6       128000
792005-08-31 22:09:29.034 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
802005-08-31 22:09:29.035 write -> 18 68      QUERY_RECORDER 1[]:[]SEEK_RINGBUF[]:[]0[]:[]0[]:[]0[]:[]0[]:[]851968
812005-08-31 22:09:29.054 read  <- 18 7       0[]:[]0
822005-08-31 22:09:29.054 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
832005-08-31 22:09:29.066 read  <- 18 6       128000
842005-08-31 22:09:29.066 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
852005-08-31 22:09:29.070 read  <- 16 475     BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
862005-08-31 22:09:29.074 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
872005-08-31 22:09:29.082 read  <- 18 6       128000
882005-08-31 22:09:29.083 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
892005-08-31 22:09:29.090 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
902005-08-31 22:09:29.099 read  <- 18 6       128000
912005-08-31 22:09:29.099 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
922005-08-31 22:09:29.106 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
932005-08-31 22:09:29.115 read  <- 18 6       128000
942005-08-31 22:09:29.115 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
952005-08-31 22:09:29.122 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
962005-08-31 22:09:29.126 read  <- 16 475     BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
972005-08-31 22:09:29.131 read  <- 18 6       128000
982005-08-31 22:09:29.131 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
992005-08-31 22:09:29.138 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1002005-08-31 22:09:29.147 read  <- 18 6       128000
1012005-08-31 22:09:29.147 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1022005-08-31 22:09:29.154 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1032005-08-31 22:09:29.163 read  <- 18 6       128000
1042005-08-31 22:09:29.163 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1052005-08-31 22:09:29.171 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1062005-08-31 22:09:29.179 read  <- 18 6       128000
1072005-08-31 22:09:29.179 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1082005-08-31 22:09:29.187 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1092005-08-31 22:09:29.195 read  <- 18 6       128000
1102005-08-31 22:09:29.196 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1112005-08-31 22:09:29.203 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1122005-08-31 22:09:29.211 read  <- 18 6       128000
1132005-08-31 22:09:29.211 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1142005-08-31 22:09:29.219 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1152005-08-31 22:09:29.228 read  <- 18 6       128000
1162005-08-31 22:09:29.228 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1172005-08-31 22:09:29.235 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1182005-08-31 22:09:29.244 read  <- 18 6       128000
1192005-08-31 22:09:29.244 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1202005-08-31 22:09:29.251 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1212005-08-31 22:09:29.260 read  <- 18 6       128000
1222005-08-31 22:09:29.260 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1232005-08-31 22:09:29.267 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1242005-08-31 22:09:29.276 read  <- 18 6       128000
1252005-08-31 22:09:29.276 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1262005-08-31 22:09:29.283 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1272005-08-31 22:09:29.292 read  <- 18 6       128000
1282005-08-31 22:09:29.292 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1292005-08-31 22:09:29.299 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1302005-08-31 22:09:29.308 read  <- 18 6       128000
1312005-08-31 22:09:29.308 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1322005-08-31 22:09:29.315 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1332005-08-31 22:09:29.324 read  <- 18 6       128000
1342005-08-31 22:09:29.324 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1352005-08-31 22:09:29.331 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1362005-08-31 22:09:29.340 read  <- 18 6       128000
1372005-08-31 22:09:29.340 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1382005-08-31 22:09:29.347 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1392005-08-31 22:09:29.356 read  <- 18 6       128000
1402005-08-31 22:09:29.356 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1412005-08-31 22:09:29.364 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1422005-08-31 22:09:29.372 read  <- 18 6       128000
1432005-08-31 22:09:29.372 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1442005-08-31 22:09:29.380 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1452005-08-31 22:09:29.388 read  <- 18 6       128000
1462005-08-31 22:09:29.388 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1472005-08-31 22:09:29.396 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1482005-08-31 22:09:29.404 read  <- 18 6       128000
1492005-08-31 22:09:29.405 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1502005-08-31 22:09:29.412 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1512005-08-31 22:09:29.421 read  <- 18 6       128000
1522005-08-31 22:09:29.421 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1532005-08-31 22:09:29.428 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1542005-08-31 22:09:29.437 read  <- 18 6       128000
1552005-08-31 22:09:29.437 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1562005-08-31 22:09:29.444 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1572005-08-31 22:09:29.453 read  <- 18 6       128000
1582005-08-31 22:09:29.453 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1592005-08-31 22:09:29.460 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1602005-08-31 22:09:29.469 read  <- 18 6       128000
1612005-08-31 22:09:29.469 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1622005-08-31 22:09:29.477 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1632005-08-31 22:09:29.485 read  <- 18 6       128000
1642005-08-31 22:09:29.485 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1652005-08-31 22:09:29.493 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1662005-08-31 22:09:29.501 read  <- 18 6       128000
1672005-08-31 22:09:29.501 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1682005-08-31 22:09:29.508 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1692005-08-31 22:09:29.517 read  <- 18 6       128000
1702005-08-31 22:09:29.517 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1712005-08-31 22:09:29.524 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1722005-08-31 22:09:29.533 read  <- 18 6       128000
1732005-08-31 22:09:29.533 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1742005-08-31 22:09:29.540 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1752005-08-31 22:09:29.549 read  <- 18 6       128000
1762005-08-31 22:09:29.549 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1772005-08-31 22:09:29.557 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1782005-08-31 22:09:29.565 read  <- 18 6       128000
1792005-08-31 22:09:29.565 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1802005-08-31 22:09:29.573 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1812005-08-31 22:09:29.581 read  <- 18 6       128000
1822005-08-31 22:09:29.582 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1832005-08-31 22:09:29.589 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1842005-08-31 22:09:29.597 read  <- 18 6       128000
1852005-08-31 22:09:29.597 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1862005-08-31 22:09:29.605 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1872005-08-31 22:09:29.614 read  <- 18 6       128000
1882005-08-31 22:09:29.614 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1892005-08-31 22:09:29.625 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1902005-08-31 22:09:29.634 read  <- 18 6       128000
1912005-08-31 22:09:29.634 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1922005-08-31 22:09:29.641 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1932005-08-31 22:09:29.650 read  <- 18 6       128000
1942005-08-31 22:09:29.650 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1952005-08-31 22:09:29.657 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1962005-08-31 22:09:29.666 read  <- 18 6       128000
1972005-08-31 22:09:29.666 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
1982005-08-31 22:09:29.677 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
1992005-08-31 22:09:29.690 read  <- 18 6       128000
2002005-08-31 22:09:29.690 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2012005-08-31 22:09:29.701 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2022005-08-31 22:09:29.710 read  <- 18 6       128000
2032005-08-31 22:09:29.710 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2042005-08-31 22:09:29.721 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2052005-08-31 22:09:29.742 read  <- 18 6       128000
2062005-08-31 22:09:29.742 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2072005-08-31 22:09:29.754 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2082005-08-31 22:09:29.766 read  <- 18 6       128000
2092005-08-31 22:09:29.767 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2102005-08-31 22:09:29.778 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2112005-08-31 22:09:29.790 read  <- 18 6       128000
2122005-08-31 22:09:29.791 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2132005-08-31 22:09:29.802 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2142005-08-31 22:09:29.814 read  <- 18 6       128000
2152005-08-31 22:09:29.814 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2162005-08-31 22:09:29.826 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2172005-08-31 22:09:29.838 read  <- 18 6       128000
2182005-08-31 22:09:29.839 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2192005-08-31 22:09:29.846 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2202005-08-31 22:09:29.855 read  <- 18 6       128000
2212005-08-31 22:09:29.855 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2222005-08-31 22:09:29.862 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2232005-08-31 22:09:29.871 read  <- 18 6       128000
2242005-08-31 22:09:29.871 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2252005-08-31 22:09:29.878 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2262005-08-31 22:09:29.887 read  <- 18 6       128000
2272005-08-31 22:09:29.887 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2282005-08-31 22:09:29.894 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2292005-08-31 22:09:29.903 read  <- 18 6       128000
2302005-08-31 22:09:29.903 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2312005-08-31 22:09:29.910 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2322005-08-31 22:09:29.919 read  <- 18 6       128000
2332005-08-31 22:09:29.919 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2342005-08-31 22:09:29.926 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2352005-08-31 22:09:29.935 read  <- 18 6       128000
2362005-08-31 22:09:29.935 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2372005-08-31 22:09:29.943 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2382005-08-31 22:09:29.951 read  <- 18 6       128000
2392005-08-31 22:09:29.951 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2402005-08-31 22:09:29.959 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2412005-08-31 22:09:29.967 read  <- 18 6       128000
2422005-08-31 22:09:29.967 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2432005-08-31 22:09:29.975 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2442005-08-31 22:09:30.064 read  <- 18 6       128000
2452005-08-31 22:09:30.064 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2462005-08-31 22:09:30.071 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2472005-08-31 22:09:30.116 read  <- 18 6       128000
2482005-08-31 22:09:30.117 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2492005-08-31 22:09:30.124 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2502005-08-31 22:09:30.132 read  <- 18 6       128000
2512005-08-31 22:09:30.132 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2522005-08-31 22:09:30.140 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2532005-08-31 22:09:30.213 read  <- 18 6       128000
2542005-08-31 22:09:30.213 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2552005-08-31 22:09:30.220 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2562005-08-31 22:09:30.309 read  <- 18 6       128000
2572005-08-31 22:09:30.309 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2582005-08-31 22:09:30.316 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2592005-08-31 22:09:30.369 read  <- 18 6       128000
2602005-08-31 22:09:30.370 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2612005-08-31 22:09:30.377 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2622005-08-31 22:09:30.466 read  <- 18 6       128000
2632005-08-31 22:09:30.466 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2642005-08-31 22:09:30.467 write -> 18 75      QUERY_RECORDER 1[]:[]SEEK_RINGBUF[]:[]0[]:[]7569408[]:[]0[]:[]0[]...
2652005-08-31 22:09:30.485 read  <- 18 13      0[]:[]7569408
2660: start_time: -9223372036854.775 duration: -9223372036854.775
267stream: start_time: -9223372036854.775 duration: -9223372036854.775 bitrate=1152 kb/s
2682005-08-31 22:09:30.486 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
2692005-08-31 22:09:30.486 AVFD: Stream #0, has id 0x33 codec id MPEG1VIDEO, type Video at 0x0x82a95a0
2702005-08-31 22:09:30.487 XvMC version: 1.0
2712005-08-31 22:09:30.488 XvMCSurfaceTypes::find(w 768, h 576, chroma 1, vld 0, idct 1, mpeg1, sub-width 0, sub-height 0, disp, p<= 270, 2700 <=p, port, surfNum)
2722005-08-31 22:09:30.488 Trying XvMC port 270
2732005-08-31 22:09:30.489 XvMCSurfaceTypes::find(w 768, h 576, chroma 1, vld 0, idct 1, mpeg1, sub-width 0, sub-height 0, disp, p<= 302, 2710 <=p, port, surfNum)
2742005-08-31 22:09:30.489 Trying XvMC port 271
2752005-08-31 22:09:30.490 Trying XvMC port 272
2762005-08-31 22:09:30.490 Trying XvMC port 273
2772005-08-31 22:09:30.490 Trying XvMC port 274
2782005-08-31 22:09:30.490 Trying XvMC port 275
2792005-08-31 22:09:30.490 Trying XvMC port 276
2802005-08-31 22:09:30.490 Trying XvMC port 277
2812005-08-31 22:09:30.490 Trying XvMC port 278
2822005-08-31 22:09:30.490 Trying XvMC port 279
2832005-08-31 22:09:30.490 Trying XvMC port 280
2842005-08-31 22:09:30.491 Trying XvMC port 281
2852005-08-31 22:09:30.491 Trying XvMC port 282
2862005-08-31 22:09:30.491 Trying XvMC port 283
2872005-08-31 22:09:30.491 Trying XvMC port 284
2882005-08-31 22:09:30.491 Trying XvMC port 285
2892005-08-31 22:09:30.491 Trying XvMC port 286
2902005-08-31 22:09:30.491 Trying XvMC port 287
2912005-08-31 22:09:30.491 Trying XvMC port 288
2922005-08-31 22:09:30.491 Trying XvMC port 289
2932005-08-31 22:09:30.491 Trying XvMC port 290
2942005-08-31 22:09:30.492 Trying XvMC port 291
2952005-08-31 22:09:30.492 Trying XvMC port 292
2962005-08-31 22:09:30.492 Trying XvMC port 293
2972005-08-31 22:09:30.492 Trying XvMC port 294
2982005-08-31 22:09:30.492 Trying XvMC port 295
2992005-08-31 22:09:30.492 Trying XvMC port 296
3002005-08-31 22:09:30.492 Trying XvMC port 297
3012005-08-31 22:09:30.492 Trying XvMC port 298
3022005-08-31 22:09:30.492 Trying XvMC port 299
3032005-08-31 22:09:30.493 Trying XvMC port 300
3042005-08-31 22:09:30.493 Trying XvMC port 301
3052005-08-31 22:09:30.493 Trying XvMC port 302
3062005-08-31 22:09:30.493 XvMCSurfaceTypes::find(w 768, h 576, chroma 1, vld 0, idct 0, mpeg1, sub-width 0, sub-height 0, disp, p<= 270, 2700 <=p, port, surfNum)
3072005-08-31 22:09:30.493 Trying XvMC port 270
3082005-08-31 22:09:30.493 XvMCSurfaceTypes::find(w 768, h 576, chroma 1, vld 0, idct 0, mpeg1, sub-width 0, sub-height 0, disp, p<= 302, 2710 <=p, port, surfNum)
3092005-08-31 22:09:30.493 Trying XvMC port 271
3102005-08-31 22:09:30.493 Trying XvMC port 272
3112005-08-31 22:09:30.494 Trying XvMC port 273
3122005-08-31 22:09:30.494 Trying XvMC port 274
3132005-08-31 22:09:30.494 Trying XvMC port 275
3142005-08-31 22:09:30.494 Trying XvMC port 276
3152005-08-31 22:09:30.494 Trying XvMC port 277
3162005-08-31 22:09:30.494 Trying XvMC port 278
3172005-08-31 22:09:30.494 Trying XvMC port 279
3182005-08-31 22:09:30.494 Trying XvMC port 280
3192005-08-31 22:09:30.494 Trying XvMC port 281
3202005-08-31 22:09:30.495 Trying XvMC port 282
3212005-08-31 22:09:30.495 Trying XvMC port 283
3222005-08-31 22:09:30.495 Trying XvMC port 284
3232005-08-31 22:09:30.495 Trying XvMC port 285
3242005-08-31 22:09:30.495 Trying XvMC port 286
3252005-08-31 22:09:30.495 Trying XvMC port 287
3262005-08-31 22:09:30.495 Trying XvMC port 288
3272005-08-31 22:09:30.495 Trying XvMC port 289
3282005-08-31 22:09:30.495 Trying XvMC port 290
3292005-08-31 22:09:30.495 Trying XvMC port 291
3302005-08-31 22:09:30.496 Trying XvMC port 292
3312005-08-31 22:09:30.496 Trying XvMC port 293
3322005-08-31 22:09:30.496 Trying XvMC port 294
3332005-08-31 22:09:30.496 Trying XvMC port 295
3342005-08-31 22:09:30.496 Trying XvMC port 296
3352005-08-31 22:09:30.496 Trying XvMC port 297
3362005-08-31 22:09:30.496 Trying XvMC port 298
3372005-08-31 22:09:30.496 Trying XvMC port 299
3382005-08-31 22:09:30.496 Trying XvMC port 300
3392005-08-31 22:09:30.497 Trying XvMC port 301
3402005-08-31 22:09:30.497 Trying XvMC port 302
3412005-08-31 22:09:30.498 read  <- 18 6       128000
3422005-08-31 22:09:30.498 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3432005-08-31 22:09:30.498 detectInterlace(Detect Scan, Detect Scan, 50, 576) ->Progressive Scan
3442005-08-31 22:09:30.499 Interlaced: Progressive Scan  video_height: 576  fps: 50
3452005-08-31 22:09:30.499 AVFD: Looking for decoder for MPEG1VIDEO
3462005-08-31 22:09:30.499 AVFD: Opened codec 0x82a2860, id(MPEG1VIDEO) type(Video)
3472005-08-31 22:09:30.500 Estimated bitrate = 1152
3482005-08-31 22:09:30.500 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
3492005-08-31 22:09:30.500 write -> 17 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
3502005-08-31 22:09:30.509 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3512005-08-31 22:09:30.518 read  <- 17 7       0[]:[]0
3522005-08-31 22:09:30.518 PosMapFromEnc: Warning, tried to fetch PositionMap from Encoder but encoder returned framesWritten == 0
3532005-08-31 22:09:30.518 SyncPositionMap liveTV, from Encoder: 0 entries
3542005-08-31 22:09:30.518 recording has no position -- using libavformat
3552005-08-31 22:09:30.518 AvFormatDecoder: Successfully opened decoder for file: "rbuf://127.0.0.1:6543/mnt/store/ringbuf1.nuv". novideo(0)
3562005-08-31 22:09:30.521 VideoOutputXv()
3572005-08-31 22:09:30.527 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
3582005-08-31 22:09:30.533 @ j=3 Looking for flag[s]: XvInputMask XvImageMask
3592005-08-31 22:09:30.533 Adaptor: 0 has flag[s]: XvInputMask XvImageMask
3602005-08-31 22:09:30.533 XVideo surface found on port 270
3612005-08-31 22:09:30.567 read  <- 18 6       128000
3622005-08-31 22:09:30.567 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3632005-08-31 22:09:30.569 Couldn't get the color key color, and we need it.
364You likely won't get any video.
3652005-08-31 22:09:30.569 Image size. dispxoff 0, dispyoff: 38, dispwoff: 666, disphoff: 456
3662005-08-31 22:09:30.569 Image size. imgx 0, imgy: 0, imgw: 768, imgh: 576
3672005-08-31 22:09:30.578 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3682005-08-31 22:09:30.591 read  <- 18 6       128000
3692005-08-31 22:09:30.591 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3702005-08-31 22:09:30.602 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3712005-08-31 22:09:30.719 read  <- 18 6       128000
3722005-08-31 22:09:30.719 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3732005-08-31 22:09:30.730 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3742005-08-31 22:09:30.738 read  <- 18 6       128000
3752005-08-31 22:09:30.738 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3762005-08-31 22:09:30.750 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3772005-08-31 22:09:30.791 read  <- 18 6       128000
3782005-08-31 22:09:30.791 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3792005-08-31 22:09:30.802 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3802005-08-31 22:09:30.932 InputChanged()
3812005-08-31 22:09:30.932 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3822005-08-31 22:09:30.933 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3832005-08-31 22:09:30.934 Changing from None to WatchingLiveTV
3842005-08-31 22:09:30.935 write -> 17 35      QUERY_RECORDER 1[]:[]GET_INPUT_NAME
3852005-08-31 22:09:30.944 Using realtime priority.
3862005-08-31 22:09:30.954 read  <- 17 8       DVBInput
3872005-08-31 22:09:31.045 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
3882005-08-31 22:09:31.046 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
3892005-08-31 22:09:31.047 Using audio as timebase
3902005-08-31 22:09:31.047 Video timing method: RTC
3912005-08-31 22:09:31.047 Refresh rate: 20000, frame interval: 20000
3922005-08-31 22:09:31.047 waiting for prebuffer... 0
3932005-08-31 22:09:31.133 waiting for prebuffer... 1
3942005-08-31 22:09:31.187 read  <- 18 6       128000
3952005-08-31 22:09:31.187 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
3962005-08-31 22:09:31.198 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
3972005-08-31 22:09:31.217 waiting for prebuffer... 2
3982005-08-31 22:09:31.284 write -> 17 35      QUERY_RECORDER 1[]:[]FRONTEND_READY
3992005-08-31 22:09:31.284 detectInterlace(Detect Scan, Progressive Scan, 50, 576) ->Progressive Scan
4002005-08-31 22:09:31.284 Interlaced: Progressive Scan  video_height: 576  fps: 50
4012005-08-31 22:09:31.284 positionMap[ 0 ] == 74824.
4022005-08-31 22:09:31.297 positionMap[ 0 ] == 897888.
4032005-08-31 22:09:31.302 read  <- 17 2       ok
4042005-08-31 22:09:31.307 write -> 17 37      QUERY_RECORDER 1[]:[]GET_PROGRAM_INFO
4052005-08-31 22:09:31.313 HandleGopStart: gopset not set, syncing positionMap
4062005-08-31 22:09:31.314 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
4072005-08-31 22:09:31.326 read  <- 17 103      []:[] []:[] []:[] []:[] []:[] []:[]Nelonen[]:[]none[]:[]4[]:[]10...
4082005-08-31 22:09:31.326 write -> 17 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
4092005-08-31 22:09:31.346 read  <- 17 7       0[]:[]1
4102005-08-31 22:09:31.346 write -> 17 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
4112005-08-31 22:09:31.346 Filling position map from 1 to 0
4122005-08-31 22:09:31.366 read  <- 17 7       0[]:[]1
4132005-08-31 22:09:31.366 write -> 14 19      QUERY_GETALLPENDING
4142005-08-31 22:09:31.367 write -> 17 50      QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]1[]:[]0
4152005-08-31 22:09:31.388 read  <- 14 7       0[]:[]0
4162005-08-31 22:09:31.389 read  <- 17 2       ok
4172005-08-31 22:09:31.389 Position map filled from Encoder to: 0
4182005-08-31 22:09:31.390 SyncPositionMap liveTV, from Encoder: 2 entries
4192005-08-31 22:09:31.390 Stream initial keyframedist: 11.
4202005-08-31 22:09:31.391 positionMap[ 2 ] == 1720952.
4212005-08-31 22:09:31.415 positionMap[ 3 ] == 2544016.
4222005-08-31 22:09:31.419 write -> 17 37      QUERY_RECORDER 1[]:[]GET_PROGRAM_INFO
4232005-08-31 22:09:31.434 read  <- 17 103      []:[] []:[] []:[] []:[] []:[] []:[]Nelonen[]:[]none[]:[]4[]:[]10...
4242005-08-31 22:09:31.435 write -> 17 53      QUERY_RECORDER 1[]:[]GET_FREE_SPACE[]:[]0[]:[]7569408
4252005-08-31 22:09:31.451 read  <- 17 16      2[]:[]2083570396
4262005-08-31 22:09:31.451 write -> 17 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
4272005-08-31 22:09:31.467 read  <- 17 7       0[]:[]3
4282005-08-31 22:09:31.530 read  <- 18 6       128000
4292005-08-31 22:09:31.530 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
4302005-08-31 22:09:31.540 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
4312005-08-31 22:09:31.569 positionMap[ 4 ] == 3367080.
4322005-08-31 22:09:31.789 positionMap[ 5 ] == 4190144.
4332005-08-31 22:09:31.870 read  <- 18 6       128000
4342005-08-31 22:09:31.871 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
4352005-08-31 22:09:31.879 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
4362005-08-31 22:09:32.009 positionMap[ 6 ] == 5013208.
4372005-08-31 22:09:32.190 read  <- 18 6       128000
4382005-08-31 22:09:32.190 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
4392005-08-31 22:09:32.201 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
4402005-08-31 22:09:32.229 positionMap[ 7 ] == 5836272.
4412005-08-31 22:09:32.449 positionMap[ 8 ] == 6659336.
4422005-08-31 22:09:32.534 read  <- 18 6       128000
4432005-08-31 22:09:32.534 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
4442005-08-31 22:09:32.549 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
4452005-08-31 22:09:32.669 positionMap[ 9 ] == 7482400.
4462005-08-31 22:09:32.736 read  <- 18 6       128000
4472005-08-31 22:09:32.737 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
4482005-08-31 22:09:32.744 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
4492005-08-31 22:09:32.889 positionMap[ 10 ] == 8305464.
4502005-08-31 22:09:32.909 streams_changed 0x825db90 -- stream count 2
4512005-08-31 22:09:32.909 AvFormatDecoder::SeekReset(0, don't flush)
4522005-08-31 22:09:32.909 AVFD: Stream #0, has id 0x215 codec id MPEG2VIDEO, type Video at 0x0x8494cc0
4532005-08-31 22:09:32.911 XvMC version: 1.0
4542005-08-31 22:09:32.911 XvMCSurfaceTypes::find(w 0, h 0, chroma 1, vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 270, 2700 <=p, port, surfNum)
4552005-08-31 22:09:32.911 Trying XvMC port 270
4562005-08-31 22:09:32.911 Found a suitable XvMC surface 0
4572005-08-31 22:09:32.911 @ j=1 Looking for flag[s]: XvInputMask
4582005-08-31 22:09:32.912 Adaptor: 0 has flag[s]: XvInputMask XvImageMask
4592005-08-31 22:09:32.912 XvMCSurfaceTypes::find(w 0, h 0, chroma 1, vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 270, 2700 <=p, port, surfNum)
4602005-08-31 22:09:32.912 Trying XvMC port 270
4612005-08-31 22:09:32.912 Found a suitable XvMC surface 0
4622005-08-31 22:09:32.912 Adaptor: 1 has flag[s]: XvInputMask XvImageMask
4632005-08-31 22:09:32.912 XvMCSurfaceTypes::find(w 0, h 0, chroma 1, vld 0, idct 1, mpeg2, sub-width 0, sub-height 0, disp, p<= 302, 2710 <=p, port, surfNum)
4642005-08-31 22:09:32.912 Trying XvMC port 271
4652005-08-31 22:09:32.912 Trying XvMC port 272
4662005-08-31 22:09:32.913 Trying XvMC port 273
4672005-08-31 22:09:32.913 Trying XvMC port 274
4682005-08-31 22:09:32.913 Trying XvMC port 275
4692005-08-31 22:09:32.913 Trying XvMC port 276
4702005-08-31 22:09:32.913 Trying XvMC port 277
4712005-08-31 22:09:32.913 Trying XvMC port 278
4722005-08-31 22:09:32.913 Trying XvMC port 279
4732005-08-31 22:09:32.913 Trying XvMC port 280
4742005-08-31 22:09:32.913 Trying XvMC port 281
4752005-08-31 22:09:32.914 Trying XvMC port 282
4762005-08-31 22:09:32.914 Trying XvMC port 283
4772005-08-31 22:09:32.914 Trying XvMC port 284
4782005-08-31 22:09:32.914 Trying XvMC port 285
4792005-08-31 22:09:32.914 Trying XvMC port 286
4802005-08-31 22:09:32.914 Trying XvMC port 287
4812005-08-31 22:09:32.914 Trying XvMC port 288
4822005-08-31 22:09:32.914 Trying XvMC port 289
4832005-08-31 22:09:32.914 Trying XvMC port 290
4842005-08-31 22:09:32.915 Trying XvMC port 291
4852005-08-31 22:09:32.915 Trying XvMC port 292
4862005-08-31 22:09:32.915 Trying XvMC port 293
4872005-08-31 22:09:32.915 Trying XvMC port 294
4882005-08-31 22:09:32.915 Trying XvMC port 295
4892005-08-31 22:09:32.915 Trying XvMC port 296
4902005-08-31 22:09:32.915 Trying XvMC port 297
4912005-08-31 22:09:32.915 Trying XvMC port 298
4922005-08-31 22:09:32.916 Trying XvMC port 299
4932005-08-31 22:09:32.916 Trying XvMC port 300
4942005-08-31 22:09:32.916 Trying XvMC port 301
4952005-08-31 22:09:32.916 Trying XvMC port 302
4962005-08-31 22:09:32.916 No suitible XVideo port found
4972005-08-31 22:09:32.916 Could not open XvMC port...
498
499                        You may wish to verify that your DISPLAY
500                        environment variable does not use an external
501                        network connection.
502
503                        You may also wish to verify that
504                        /etc/X11/XvMCConfig contains the correct
505                        vendor's XvMC library.
506
5072005-08-31 22:09:32.917 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
5082005-08-31 22:09:32.917 Interlaced: Interlaced Scan  video_height: 480  fps: 29.97
5092005-08-31 22:09:32.919 Using deinterlace method bobdeint
5102005-08-31 22:09:32.919 Set video sync frame interval to 33366
5112005-08-31 22:09:32.919 Video sync method can't support double framerate (refresh rate too low for bob deint)
5122005-08-31 22:09:32.920 AVFD: Looking for decoder for MPEG2VIDEO
5132005-08-31 22:09:32.920 AVFD: Opened codec 0x839aba0, id(MPEG2VIDEO) type(Video)
5142005-08-31 22:09:32.921 AVFD: Stream #1, has id 0x888 codec id MP3, type Audio at 0x0x84f9b20
5152005-08-31 22:09:32.921 AVFD: Looking for decoder for MP3
5162005-08-31 22:09:32.940 AVFD: Opened codec 0x84682f0, id(MP3) type(Audio)
5172005-08-31 22:09:32.940 AVFD: Stream #1 (audio track #0) is an audio stream with 0 channels.
5182005-08-31 22:09:32.942 AvFormatDecoder: Video has changed from 0x0 to 704x576.
5192005-08-31 22:09:32.943 InputChanged()
5202005-08-31 22:09:32.943 VideoBuffers::DiscardFrames(): UUUUUUUUUUUUUUUUULAAUUUUUUUUUUU
5212005-08-31 22:09:32.943 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
5222005-08-31 22:09:32.943 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5232005-08-31 22:09:32.943 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
5242005-08-31 22:09:32.943 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5252005-08-31 22:09:32.943 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
5262005-08-31 22:09:32.954 prebuffering pause
5272005-08-31 22:09:32.955 waiting for prebuffer... 0
5282005-08-31 22:09:32.959 read  <- 18 6       128000
5292005-08-31 22:09:32.959 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
5302005-08-31 22:09:32.970 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
5312005-08-31 22:09:32.986 Image size. dispxoff 0, dispyoff: 0, dispwoff: 666, disphoff: 533
5322005-08-31 22:09:32.986 Image size. imgx 0, imgy: 0, imgw: 704, imgh: 576
5332005-08-31 22:09:33.122 waiting for prebuffer... 1
5342005-08-31 22:09:33.255 read  <- 18 6       128000
5352005-08-31 22:09:33.255 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
5362005-08-31 22:09:33.266 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
5372005-08-31 22:09:33.290 waiting for prebuffer... 2
5382005-08-31 22:09:33.312 detectInterlace(Detect Scan, Ignore Scan, 25, 576) ->Interlaced Scan
5392005-08-31 22:09:33.312 Interlaced: Interlaced Scan  video_height: 576  fps: 25
5402005-08-31 22:09:33.314 Using deinterlace method bobdeint
5412005-08-31 22:09:33.314 Set video sync frame interval to 40000
5422005-08-31 22:09:33.314 positionMap[ 11 ] == 8511324.
5432005-08-31 22:09:33.321 Auto-selecting audio track #1 (stream #1).
5442005-08-31 22:09:33.321 It has 2 channels and we needed at least 1
5452005-08-31 22:09:33.321 Initializing audio parms from audio track #0.
5462005-08-31 22:09:33.322 Killing AudioOutputDSP
5472005-08-31 22:09:33.323 Opening audio device 'spdif'.
5482005-08-31 22:09:33.330 in SetParameters(format=2, channels=2, rate=48000, buffer_time=500000, period_time=125000)
5492005-08-31 22:09:33.331 get_buffer_size returned 16384
5502005-08-31 22:09:33.331 set_period_time_near returned 85333
5512005-08-31 22:09:33.331 get_period_size returned 4096
5522005-08-31 22:09:33.331 Audio fragment size: 4096
5532005-08-31 22:09:33.332 Audio Stretch Factor: 1
5542005-08-31 22:09:33.332 Ending reconfigure
5552005-08-31 22:09:33.332 kickoffOutputAudioLoop: pid = 11695
5562005-08-31 22:09:33.333 OutputAudioLoop: Play Event
5572005-08-31 22:09:33.333 Broadcasting free space avail
5582005-08-31 22:09:33.336 _AddSamples bytes=4608, used=1, free=767999, timecode=13678718
5592005-08-31 22:09:33.337 _AddSamples bytes=4608, used=4609, free=763391, timecode=13678742
5602005-08-31 22:09:33.337 _AddSamples bytes=4608, used=9217, free=758783, timecode=13678766
5612005-08-31 22:09:33.341 _AddSamples bytes=4608, used=13825, free=754175, timecode=13678790
5622005-08-31 22:09:33.341 _AddSamples bytes=4608, used=18433, free=749567, timecode=13678814
5632005-08-31 22:09:33.342 _AddSamples bytes=4608, used=23041, free=744959, timecode=13678838
5642005-08-31 22:09:33.342 _AddSamples bytes=4608, used=27649, free=740351, timecode=13678862
5652005-08-31 22:09:33.342 Broadcasting free space avail
5662005-08-31 22:09:33.342 WriteAudio: Preparing 4096 bytes (1024 frames)
5672005-08-31 22:09:33.343 Broadcasting free space avail
5682005-08-31 22:09:33.343 WriteAudio: Preparing 4096 bytes (1024 frames)
5692005-08-31 22:09:33.343 _AddSamples bytes=4608, used=24065, free=743935, timecode=13678886
5702005-08-31 22:09:33.343 Broadcasting free space avail
5712005-08-31 22:09:33.343 WriteAudio: Preparing 4096 bytes (1024 frames)
5722005-08-31 22:09:33.343 Broadcasting free space avail
5732005-08-31 22:09:33.343 WriteAudio: Preparing 4096 bytes (1024 frames)
5742005-08-31 22:09:33.343 Broadcasting free space avail
5752005-08-31 22:09:33.344 WriteAudio: Preparing 4096 bytes (1024 frames)
5762005-08-31 22:09:33.344 _AddSamples bytes=4608, used=16385, free=751615, timecode=13678910
5772005-08-31 22:09:33.344 Broadcasting free space avail
5782005-08-31 22:09:33.344 WriteAudio: Preparing 4096 bytes (1024 frames)
5792005-08-31 22:09:33.344 Broadcasting free space avail
5802005-08-31 22:09:33.344 WriteAudio: Preparing 4096 bytes (1024 frames)
5812005-08-31 22:09:33.344 _AddSamples bytes=4608, used=12801, free=755199, timecode=13678934
5822005-08-31 22:09:33.344 Broadcasting free space avail
5832005-08-31 22:09:33.345 WriteAudio: Preparing 4096 bytes (1024 frames)
5842005-08-31 22:09:33.345 Broadcasting free space avail
5852005-08-31 22:09:33.345 WriteAudio: Preparing 4096 bytes (1024 frames)
5862005-08-31 22:09:33.345 Broadcasting free space avail
5872005-08-31 22:09:33.345 WriteAudio: Preparing 4096 bytes (1024 frames)
5882005-08-31 22:09:33.345 _AddSamples bytes=4608, used=5121, free=762879, timecode=13678958
5892005-08-31 22:09:33.345 Broadcasting free space avail
5902005-08-31 22:09:33.345 WriteAudio: Preparing 4096 bytes (1024 frames)
5912005-08-31 22:09:33.346 Broadcasting free space avail
5922005-08-31 22:09:33.346 WriteAudio: Preparing 4096 bytes (1024 frames)
5932005-08-31 22:09:33.346 _AddSamples bytes=4608, used=1537, free=766463, timecode=13678982
5942005-08-31 22:09:33.346 Broadcasting free space avail
5952005-08-31 22:09:33.346 WriteAudio: Preparing 4096 bytes (1024 frames)
5962005-08-31 22:09:33.346 _AddSamples bytes=4608, used=2049, free=765951, timecode=13679006
5972005-08-31 22:09:33.347 Broadcasting free space avail
5982005-08-31 22:09:33.347 WriteAudio: Preparing 4096 bytes (1024 frames)
5992005-08-31 22:09:33.347 audio waiting for buffer to fill: have 2560 want 4096
6002005-08-31 22:09:33.347 Broadcasting free space avail
6012005-08-31 22:09:33.347 _AddSamples bytes=4608, used=2561, free=765439, timecode=13679030
6022005-08-31 22:09:33.348 _AddSamples bytes=4608, used=7169, free=760831, timecode=13679054
6032005-08-31 22:09:33.348 _AddSamples bytes=4608, used=11777, free=756223, timecode=13679078
6042005-08-31 22:09:33.348 _AddSamples bytes=4608, used=16385, free=751615, timecode=13679102
6052005-08-31 22:09:33.349 _AddSamples bytes=4608, used=20993, free=747007, timecode=13679126
6062005-08-31 22:09:33.349 _AddSamples bytes=4608, used=25601, free=742399, timecode=13679150
6072005-08-31 22:09:33.350 _AddSamples bytes=4608, used=30209, free=737791, timecode=13679174
6082005-08-31 22:09:33.350 _AddSamples bytes=4608, used=34817, free=733183, timecode=13679198
6092005-08-31 22:09:33.351 _AddSamples bytes=4608, used=39425, free=728575, timecode=13679222
6102005-08-31 22:09:33.351 _AddSamples bytes=4608, used=44033, free=723967, timecode=13679246
6112005-08-31 22:09:33.352 _AddSamples bytes=4608, used=48641, free=719359, timecode=13679270
6122005-08-31 22:09:33.352 _AddSamples bytes=4608, used=53249, free=714751, timecode=13679294
6132005-08-31 22:09:33.353 _AddSamples bytes=4608, used=57857, free=710143, timecode=13679318
6142005-08-31 22:09:33.353 _AddSamples bytes=4608, used=62465, free=705535, timecode=13679342
6152005-08-31 22:09:33.356 _AddSamples bytes=4608, used=67073, free=700927, timecode=13679366
6162005-08-31 22:09:33.357 _AddSamples bytes=4608, used=71681, free=696319, timecode=13679390
6172005-08-31 22:09:33.358 Broadcasting free space avail
6182005-08-31 22:09:33.358 WriteAudio: Preparing 4096 bytes (1024 frames)
6192005-08-31 22:09:33.359 Broadcasting free space avail
6202005-08-31 22:09:33.359 WriteAudio: Preparing 4096 bytes (1024 frames)
6212005-08-31 22:09:33.359 audio waiting for space on soundcard: have 2972 need 4096
6222005-08-31 22:09:33.361 _AddSamples bytes=4608, used=68097, free=699903, timecode=13679414
6232005-08-31 22:09:33.361 _AddSamples bytes=4608, used=72705, free=695295, timecode=13679438
6242005-08-31 22:09:33.365 _AddSamples bytes=4608, used=77313, free=690687, timecode=13679462
6252005-08-31 22:09:33.368 _AddSamples bytes=4608, used=81921, free=686079, timecode=13679486
6262005-08-31 22:09:33.368 _AddSamples bytes=4608, used=86529, free=681471, timecode=13679510
6272005-08-31 22:09:33.372 _AddSamples bytes=4608, used=91137, free=676863, timecode=13679534
6282005-08-31 22:09:33.372 _AddSamples bytes=4608, used=95745, free=672255, timecode=13679558
6292005-08-31 22:09:33.374 Broadcasting free space avail
6302005-08-31 22:09:33.374 WriteAudio: Preparing 4096 bytes (1024 frames)
6312005-08-31 22:09:33.375 audio waiting for space on soundcard: have 1892 need 4096
6322005-08-31 22:09:33.376 _AddSamples bytes=4608, used=96257, free=671743, timecode=13679582
6332005-08-31 22:09:33.376 HandleGopStart: gopset not set, syncing positionMap
6342005-08-31 22:09:33.376 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
6352005-08-31 22:09:33.376 write -> 17 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
6362005-08-31 22:09:33.386 Broadcasting free space avail
6372005-08-31 22:09:33.386 WriteAudio: Preparing 4096 bytes (1024 frames)
6382005-08-31 22:09:33.387 audio waiting for space on soundcard: have 108 need 4096
6392005-08-31 22:09:33.394 read  <- 17 8       0[]:[]49
6402005-08-31 22:09:33.395 Filling position map from 12 to 4
6412005-08-31 22:09:33.395 write -> 17 51      QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]12[]:[]4
6422005-08-31 22:09:33.398 audio waiting for space on soundcard: have 2392 need 4096
6432005-08-31 22:09:33.411 read  <- 17 2       ok
6442005-08-31 22:09:33.411 Broadcasting free space avail
6452005-08-31 22:09:33.411 Position map filled from Encoder to: 11
6462005-08-31 22:09:33.411 WriteAudio: Preparing 4096 bytes (1024 frames)
6472005-08-31 22:09:33.411 SyncPositionMap liveTV, from Encoder: 12 entries
6482005-08-31 22:09:33.411 audio waiting for space on soundcard: have 736 need 4096
6492005-08-31 22:09:33.411 Stream initial keyframedist: 12.
6502005-08-31 22:09:33.411 positionMap[ 11 ] == 8684472.
6512005-08-31 22:09:33.415 _AddSamples bytes=4608, used=92673, free=675327, timecode=13679606
6522005-08-31 22:09:33.416 _AddSamples bytes=4608, used=97281, free=670719, timecode=13679630
6532005-08-31 22:09:33.420 _AddSamples bytes=4608, used=101889, free=666111, timecode=13679654
6542005-08-31 22:09:33.420 _AddSamples bytes=4608, used=106497, free=661503, timecode=13679678
6552005-08-31 22:09:33.423 audio waiting for space on soundcard: have 3004 need 4096
6562005-08-31 22:09:33.425 _AddSamples bytes=4608, used=111105, free=656895, timecode=13679702
6572005-08-31 22:09:33.435 Broadcasting free space avail
6582005-08-31 22:09:33.435 WriteAudio: Preparing 4096 bytes (1024 frames)
6592005-08-31 22:09:33.435 _AddSamples bytes=4608, used=111617, free=656383, timecode=13679726
6602005-08-31 22:09:33.435 audio waiting for space on soundcard: have 1256 need 4096
6612005-08-31 22:09:33.436 _AddSamples bytes=4608, used=116225, free=651775, timecode=13679750
6622005-08-31 22:09:33.440 _AddSamples bytes=4608, used=120833, free=647167, timecode=13679774
6632005-08-31 22:09:33.440 _AddSamples bytes=4608, used=125441, free=642559, timecode=13679798
6642005-08-31 22:09:33.444 _AddSamples bytes=4608, used=130049, free=637951, timecode=13679822
6652005-08-31 22:09:33.447 audio waiting for space on soundcard: have 3596 need 4096
6662005-08-31 22:09:33.450 _AddSamples bytes=4608, used=134657, free=633343, timecode=13679846
6672005-08-31 22:09:33.451 _AddSamples bytes=4608, used=139265, free=628735, timecode=13679870
6682005-08-31 22:09:33.455 _AddSamples bytes=4608, used=143873, free=624127, timecode=13679894
6692005-08-31 22:09:33.456 _AddSamples bytes=4608, used=148481, free=619519, timecode=13679918
6702005-08-31 22:09:33.459 Broadcasting free space avail
6712005-08-31 22:09:33.459 WriteAudio: Preparing 4096 bytes (1024 frames)
6722005-08-31 22:09:33.459 audio waiting for space on soundcard: have 1756 need 4096
6732005-08-31 22:09:33.460 _AddSamples bytes=4608, used=148993, free=619007, timecode=13679942
6742005-08-31 22:09:33.465 _AddSamples bytes=4608, used=153601, free=614399, timecode=13679966
6752005-08-31 22:09:33.465 _AddSamples bytes=4608, used=158209, free=609791, timecode=13679990
6762005-08-31 22:09:33.470 _AddSamples bytes=4608, used=162817, free=605183, timecode=13680014
6772005-08-31 22:09:33.470 _AddSamples bytes=4608, used=167425, free=600575, timecode=13680038
6782005-08-31 22:09:33.471 audio waiting for space on soundcard: have 4008 need 4096
6792005-08-31 22:09:33.475 _AddSamples bytes=4608, used=172033, free=595967, timecode=13680062
6802005-08-31 22:09:33.475 positionMap[ 12 ] == 9011592.
6812005-08-31 22:09:33.479 _AddSamples bytes=4608, used=176641, free=591359, timecode=13680086
6822005-08-31 22:09:33.479 _AddSamples bytes=4608, used=181249, free=586751, timecode=13680110
6832005-08-31 22:09:33.483 _AddSamples bytes=4608, used=185857, free=582143, timecode=13680134
6842005-08-31 22:09:33.483 Broadcasting free space avail
6852005-08-31 22:09:33.483 WriteAudio: Preparing 4096 bytes (1024 frames)
6862005-08-31 22:09:33.483 audio waiting for space on soundcard: have 2292 need 4096
6872005-08-31 22:09:33.484 _AddSamples bytes=4608, used=186369, free=581631, timecode=13680158
6882005-08-31 22:09:33.488 _AddSamples bytes=4608, used=190977, free=577023, timecode=13680182
6892005-08-31 22:09:33.492 _AddSamples bytes=4608, used=195585, free=572415, timecode=13680206
6902005-08-31 22:09:33.492 _AddSamples bytes=4608, used=200193, free=567807, timecode=13680230
6912005-08-31 22:09:33.495 Broadcasting free space avail
6922005-08-31 22:09:33.496 WriteAudio: Preparing 4096 bytes (1024 frames)
6932005-08-31 22:09:33.496 audio waiting for space on soundcard: have 636 need 4096
6942005-08-31 22:09:33.496 _AddSamples bytes=4608, used=200705, free=567295, timecode=13680254
6952005-08-31 22:09:33.497 _AddSamples bytes=4608, used=205313, free=562687, timecode=13680278
6962005-08-31 22:09:33.501 _AddSamples bytes=4608, used=209921, free=558079, timecode=13680302
6972005-08-31 22:09:33.505 _AddSamples bytes=4608, used=214529, free=553471, timecode=13680326
6982005-08-31 22:09:33.508 audio waiting for space on soundcard: have 2824 need 4096
6992005-08-31 22:09:33.508 _AddSamples bytes=4608, used=219137, free=548863, timecode=13680350
7002005-08-31 22:09:33.512 _AddSamples bytes=4608, used=223745, free=544255, timecode=13680374
7012005-08-31 22:09:33.519 Broadcasting free space avail
7022005-08-31 22:09:33.519 WriteAudio: Preparing 4096 bytes (1024 frames)
7032005-08-31 22:09:33.519 audio waiting for space on soundcard: have 1016 need 4096
7042005-08-31 22:09:33.531 audio waiting for space on soundcard: have 3308 need 4096
7052005-08-31 22:09:33.544 Broadcasting free space avail
7062005-08-31 22:09:33.544 WriteAudio: Preparing 4096 bytes (1024 frames)
7072005-08-31 22:09:33.544 audio waiting for space on soundcard: have 1600 need 4096
7082005-08-31 22:09:33.546 A/V diverged by 3.87188 frames, extending frame to keep audio in sync
7092005-08-31 22:09:33.556 audio waiting for space on soundcard: have 3904 need 4096
7102005-08-31 22:09:33.568 Broadcasting free space avail
7112005-08-31 22:09:33.568 WriteAudio: Preparing 4096 bytes (1024 frames)
7122005-08-31 22:09:33.568 audio waiting for space on soundcard: have 2208 need 4096
7132005-08-31 22:09:33.580 Broadcasting free space avail
7142005-08-31 22:09:33.580 WriteAudio: Preparing 4096 bytes (1024 frames)
7152005-08-31 22:09:33.580 audio waiting for space on soundcard: have 456 need 4096
7162005-08-31 22:09:33.592 audio waiting for space on soundcard: have 2768 need 4096
7172005-08-31 22:09:33.605 Broadcasting free space avail
7182005-08-31 22:09:33.605 WriteAudio: Preparing 4096 bytes (1024 frames)
7192005-08-31 22:09:33.605 audio waiting for space on soundcard: have 1104 need 4096
7202005-08-31 22:09:33.617 audio waiting for space on soundcard: have 3408 need 4096
7212005-08-31 22:09:33.626 A/V diverged by 5.06015 frames, extending frame to keep audio in sync
7222005-08-31 22:09:33.628 read  <- 18 6       128000
7232005-08-31 22:09:33.628 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
7242005-08-31 22:09:33.629 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
7252005-08-31 22:09:33.629 _AddSamples bytes=4608, used=207873, free=560127, timecode=13680398
7262005-08-31 22:09:33.629 Broadcasting free space avail
7272005-08-31 22:09:33.630 WriteAudio: Preparing 4096 bytes (1024 frames)
7282005-08-31 22:09:33.630 audio waiting for space on soundcard: have 1724 need 4096
7292005-08-31 22:09:33.633 _AddSamples bytes=4608, used=208385, free=559615, timecode=13680422
7302005-08-31 22:09:33.637 _AddSamples bytes=4608, used=212993, free=555007, timecode=13680446
7312005-08-31 22:09:33.638 _AddSamples bytes=4608, used=217601, free=550399, timecode=13680470
7322005-08-31 22:09:33.641 audio waiting for space on soundcard: have 3960 need 4096
7332005-08-31 22:09:33.642 _AddSamples bytes=4608, used=222209, free=545791, timecode=13680494
7342005-08-31 22:09:33.642 _AddSamples bytes=4608, used=226817, free=541183, timecode=13680518
7352005-08-31 22:09:33.646 _AddSamples bytes=4608, used=231425, free=536575, timecode=13680542
7362005-08-31 22:09:33.646 positionMap[ 13 ] == 9215384.
7372005-08-31 22:09:33.650 _AddSamples bytes=4608, used=236033, free=531967, timecode=13680566
7382005-08-31 22:09:33.650 _AddSamples bytes=4608, used=240641, free=527359, timecode=13680590
7392005-08-31 22:09:33.653 Broadcasting free space avail
7402005-08-31 22:09:33.653 WriteAudio: Preparing 4096 bytes (1024 frames)
7412005-08-31 22:09:33.654 audio waiting for space on soundcard: have 2220 need 4096
7422005-08-31 22:09:33.666 Broadcasting free space avail
7432005-08-31 22:09:33.666 WriteAudio: Preparing 4096 bytes (1024 frames)
7442005-08-31 22:09:33.666 audio waiting for space on soundcard: have 484 need 4096
7452005-08-31 22:09:33.678 audio waiting for space on soundcard: have 2792 need 4096
7462005-08-31 22:09:33.690 Broadcasting free space avail
7472005-08-31 22:09:33.690 WriteAudio: Preparing 4096 bytes (1024 frames)
7482005-08-31 22:09:33.690 audio waiting for space on soundcard: have 1108 need 4096
7492005-08-31 22:09:33.703 audio waiting for space on soundcard: have 3420 need 4096
7502005-08-31 22:09:33.706 A/V diverged by 5.70135 frames, extending frame to keep audio in sync
7512005-08-31 22:09:33.711 _AddSamples bytes=4608, used=232961, free=535039, timecode=13680614
7522005-08-31 22:09:33.712 _AddSamples bytes=4608, used=237569, free=530431, timecode=13680638
7532005-08-31 22:09:33.715 Broadcasting free space avail
7542005-08-31 22:09:33.715 WriteAudio: Preparing 4096 bytes (1024 frames)
7552005-08-31 22:09:33.715 audio waiting for space on soundcard: have 1704 need 4096
7562005-08-31 22:09:33.727 audio waiting for space on soundcard: have 4044 need 4096
7572005-08-31 22:09:33.739 Broadcasting free space avail
7582005-08-31 22:09:33.739 WriteAudio: Preparing 4096 bytes (1024 frames)
7592005-08-31 22:09:33.740 audio waiting for space on soundcard: have 2332 need 4096
7602005-08-31 22:09:33.751 Broadcasting free space avail
7612005-08-31 22:09:33.752 WriteAudio: Preparing 4096 bytes (1024 frames)
7622005-08-31 22:09:33.752 audio waiting for space on soundcard: have 572 need 4096
7632005-08-31 22:09:33.764 audio waiting for space on soundcard: have 2916 need 4096
7642005-08-31 22:09:33.776 Broadcasting free space avail
7652005-08-31 22:09:33.776 WriteAudio: Preparing 4096 bytes (1024 frames)
7662005-08-31 22:09:33.776 audio waiting for space on soundcard: have 1232 need 4096
7672005-08-31 22:09:33.786 A/V diverged by 5.93225 frames, extending frame to keep audio in sync
7682005-08-31 22:09:33.788 audio waiting for space on soundcard: have 3516 need 4096
7692005-08-31 22:09:33.791 _AddSamples bytes=4608, used=225793, free=542207, timecode=13680662
7702005-08-31 22:09:33.801 Broadcasting free space avail
7712005-08-31 22:09:33.801 WriteAudio: Preparing 4096 bytes (1024 frames)
7722005-08-31 22:09:33.801 audio waiting for space on soundcard: have 1812 need 4096
7732005-08-31 22:09:33.813 Broadcasting free space avail
7742005-08-31 22:09:33.813 WriteAudio: Preparing 4096 bytes (1024 frames)
7752005-08-31 22:09:33.813 audio waiting for space on soundcard: have 60 need 4096
7762005-08-31 22:09:33.825 audio waiting for space on soundcard: have 2384 need 4096
7772005-08-31 22:09:33.837 Broadcasting free space avail
7782005-08-31 22:09:33.837 WriteAudio: Preparing 4096 bytes (1024 frames)
7792005-08-31 22:09:33.838 audio waiting for space on soundcard: have 672 need 4096
7802005-08-31 22:09:33.850 audio waiting for space on soundcard: have 2996 need 4096
7812005-08-31 22:09:33.862 Broadcasting free space avail
7822005-08-31 22:09:33.862 WriteAudio: Preparing 4096 bytes (1024 frames)
7832005-08-31 22:09:33.862 audio waiting for space on soundcard: have 1304 need 4096
7842005-08-31 22:09:33.866 A/V diverged by 5.85542 frames, extending frame to keep audio in sync
7852005-08-31 22:09:33.871 _AddSamples bytes=4608, used=214017, free=553983, timecode=13680686
7862005-08-31 22:09:33.872 _AddSamples bytes=4608, used=218625, free=549375, timecode=13680710
7872005-08-31 22:09:33.878 Broadcasting free space avail
7882005-08-31 22:09:33.878 WriteAudio: Preparing 4096 bytes (1024 frames)
7892005-08-31 22:09:33.878 audio waiting for space on soundcard: have 340 need 4096
7902005-08-31 22:09:33.891 audio waiting for space on soundcard: have 2648 need 4096
7912005-08-31 22:09:33.903 Broadcasting free space avail
7922005-08-31 22:09:33.903 WriteAudio: Preparing 4096 bytes (1024 frames)
7932005-08-31 22:09:33.903 audio waiting for space on soundcard: have 952 need 4096
7942005-08-31 22:09:33.915 audio waiting for space on soundcard: have 3256 need 4096
7952005-08-31 22:09:33.927 Broadcasting free space avail
7962005-08-31 22:09:33.927 WriteAudio: Preparing 4096 bytes (1024 frames)
7972005-08-31 22:09:33.927 audio waiting for space on soundcard: have 1560 need 4096
7982005-08-31 22:09:33.940 audio waiting for space on soundcard: have 3872 need 4096
7992005-08-31 22:09:33.946 A/V diverged by 5.5478 frames, extending frame to keep audio in sync
8002005-08-31 22:09:33.951 _AddSamples bytes=4608, used=210945, free=557055, timecode=13680734
8012005-08-31 22:09:33.952 Broadcasting free space avail
8022005-08-31 22:09:33.952 WriteAudio: Preparing 4096 bytes (1024 frames)
8032005-08-31 22:09:33.952 audio waiting for space on soundcard: have 2148 need 4096
8042005-08-31 22:09:33.962 read  <- 18 6       128000
8052005-08-31 22:09:33.962 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
8062005-08-31 22:09:33.963 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
8072005-08-31 22:09:33.963 _AddSamples bytes=4608, used=211457, free=556543, timecode=13680758
8082005-08-31 22:09:33.964 Broadcasting free space avail
8092005-08-31 22:09:33.964 WriteAudio: Preparing 4096 bytes (1024 frames)
8102005-08-31 22:09:33.964 audio waiting for space on soundcard: have 400 need 4096
8112005-08-31 22:09:33.976 audio waiting for space on soundcard: have 2680 need 4096
8122005-08-31 22:09:33.988 Broadcasting free space avail
8132005-08-31 22:09:33.988 WriteAudio: Preparing 4096 bytes (1024 frames)
8142005-08-31 22:09:33.989 audio waiting for space on soundcard: have 992 need 4096
8152005-08-31 22:09:34.001 audio waiting for space on soundcard: have 3320 need 4096
8162005-08-31 22:09:34.013 Broadcasting free space avail
8172005-08-31 22:09:34.013 WriteAudio: Preparing 4096 bytes (1024 frames)
8182005-08-31 22:09:34.013 audio waiting for space on soundcard: have 1648 need 4096
8192005-08-31 22:09:34.025 audio waiting for space on soundcard: have 3928 need 4096
8202005-08-31 22:09:34.028 A/V diverged by 5.0671 frames, extending frame to keep audio in sync
8212005-08-31 22:09:34.033 _AddSamples bytes=4608, used=203777, free=564223, timecode=13680782
8222005-08-31 22:09:34.037 Broadcasting free space avail
8232005-08-31 22:09:34.037 WriteAudio: Preparing 4096 bytes (1024 frames)
8242005-08-31 22:09:34.038 audio waiting for space on soundcard: have 2212 need 4096
8252005-08-31 22:09:34.050 Broadcasting free space avail
8262005-08-31 22:09:34.050 WriteAudio: Preparing 4096 bytes (1024 frames)
8272005-08-31 22:09:34.050 audio waiting for space on soundcard: have 468 need 4096
8282005-08-31 22:09:34.062 audio waiting for space on soundcard: have 2768 need 4096
8292005-08-31 22:09:34.074 Broadcasting free space avail
8302005-08-31 22:09:34.074 WriteAudio: Preparing 4096 bytes (1024 frames)
8312005-08-31 22:09:34.074 audio waiting for space on soundcard: have 1088 need 4096
8322005-08-31 22:09:34.087 audio waiting for space on soundcard: have 3424 need 4096
8332005-08-31 22:09:34.099 Broadcasting free space avail
8342005-08-31 22:09:34.099 WriteAudio: Preparing 4096 bytes (1024 frames)
8352005-08-31 22:09:34.099 audio waiting for space on soundcard: have 1712 need 4096
8362005-08-31 22:09:34.108 A/V diverged by 4.45033 frames, extending frame to keep audio in sync
8372005-08-31 22:09:34.111 audio waiting for space on soundcard: have 4028 need 4096
8382005-08-31 22:09:34.113 _AddSamples bytes=4608, used=192001, free=575999, timecode=13680806
8392005-08-31 22:09:34.114 _AddSamples bytes=4608, used=196609, free=571391, timecode=13680830
8402005-08-31 22:09:34.123 Broadcasting free space avail
8412005-08-31 22:09:34.123 WriteAudio: Preparing 4096 bytes (1024 frames)
8422005-08-31 22:09:34.123 audio waiting for space on soundcard: have 2312 need 4096
8432005-08-31 22:09:34.135 Broadcasting free space avail
8442005-08-31 22:09:34.136 WriteAudio: Preparing 4096 bytes (1024 frames)
8452005-08-31 22:09:34.136 audio waiting for space on soundcard: have 572 need 4096
8462005-08-31 22:09:34.148 audio waiting for space on soundcard: have 2888 need 4096
8472005-08-31 22:09:34.160 Broadcasting free space avail
8482005-08-31 22:09:34.160 WriteAudio: Preparing 4096 bytes (1024 frames)
8492005-08-31 22:09:34.160 audio waiting for space on soundcard: have 1212 need 4096
8502005-08-31 22:09:34.172 audio waiting for space on soundcard: have 3528 need 4096
8512005-08-31 22:09:34.185 Broadcasting free space avail
8522005-08-31 22:09:34.185 WriteAudio: Preparing 4096 bytes (1024 frames)
8532005-08-31 22:09:34.185 audio waiting for space on soundcard: have 1852 need 4096
8542005-08-31 22:09:34.188 A/V diverged by 3.73148 frames, extending frame to keep audio in sync
8552005-08-31 22:09:34.193 _AddSamples bytes=4608, used=184833, free=583167, timecode=13680854
8562005-08-31 22:09:34.194 _AddSamples bytes=4608, used=189441, free=578559, timecode=13680878
8572005-08-31 22:09:34.197 Broadcasting free space avail
8582005-08-31 22:09:34.197 WriteAudio: Preparing 4096 bytes (1024 frames)
8592005-08-31 22:09:34.197 audio waiting for space on soundcard: have 72 need 4096
8602005-08-31 22:09:34.209 audio waiting for space on soundcard: have 2392 need 4096
8612005-08-31 22:09:34.221 Broadcasting free space avail
8622005-08-31 22:09:34.221 WriteAudio: Preparing 4096 bytes (1024 frames)
8632005-08-31 22:09:34.222 audio waiting for space on soundcard: have 668 need 4096
8642005-08-31 22:09:34.234 audio waiting for space on soundcard: have 2996 need 4096
8652005-08-31 22:09:34.246 Broadcasting free space avail
8662005-08-31 22:09:34.246 WriteAudio: Preparing 4096 bytes (1024 frames)
8672005-08-31 22:09:34.246 audio waiting for space on soundcard: have 1316 need 4096
8682005-08-31 22:09:34.262 Broadcasting free space avail
8692005-08-31 22:09:34.262 WriteAudio: Preparing 4096 bytes (1024 frames)
8702005-08-31 22:09:34.262 audio waiting for space on soundcard: have 336 need 4096
8712005-08-31 22:09:34.271 read  <- 18 6       128000
8722005-08-31 22:09:34.271 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
8732005-08-31 22:09:34.271 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
8742005-08-31 22:09:34.274 _AddSamples bytes=4608, used=177665, free=590335, timecode=13680902
8752005-08-31 22:09:34.274 audio waiting for space on soundcard: have 2624 need 4096
8762005-08-31 22:09:34.287 Broadcasting free space avail
8772005-08-31 22:09:34.287 WriteAudio: Preparing 4096 bytes (1024 frames)
8782005-08-31 22:09:34.287 audio waiting for space on soundcard: have 960 need 4096
8792005-08-31 22:09:34.299 audio waiting for space on soundcard: have 3260 need 4096
8802005-08-31 22:09:34.311 Broadcasting free space avail
8812005-08-31 22:09:34.311 WriteAudio: Preparing 4096 bytes (1024 frames)
8822005-08-31 22:09:34.312 audio waiting for space on soundcard: have 1584 need 4096
8832005-08-31 22:09:34.324 audio waiting for space on soundcard: have 3888 need 4096
8842005-08-31 22:09:34.333 _AddSamples bytes=4608, used=174081, free=593919, timecode=13680926
8852005-08-31 22:09:34.333 _AddSamples bytes=4608, used=178689, free=589311, timecode=13680950
8862005-08-31 22:09:34.336 Broadcasting free space avail
8872005-08-31 22:09:34.336 WriteAudio: Preparing 4096 bytes (1024 frames)
8882005-08-31 22:09:34.336 audio waiting for space on soundcard: have 2148 need 4096
8892005-08-31 22:09:34.348 Broadcasting free space avail
8902005-08-31 22:09:34.348 WriteAudio: Preparing 4096 bytes (1024 frames)
8912005-08-31 22:09:34.348 audio waiting for space on soundcard: have 460 need 4096
8922005-08-31 22:09:34.360 audio waiting for space on soundcard: have 2712 need 4096
8932005-08-31 22:09:34.372 Broadcasting free space avail
8942005-08-31 22:09:34.373 WriteAudio: Preparing 4096 bytes (1024 frames)
8952005-08-31 22:09:34.373 _AddSamples bytes=4608, used=171009, free=596991, timecode=13680974
8962005-08-31 22:09:34.373 audio waiting for space on soundcard: have 1036 need 4096
8972005-08-31 22:09:34.373 _AddSamples bytes=4608, used=175617, free=592383, timecode=13680998
8982005-08-31 22:09:34.385 audio waiting for space on soundcard: have 3308 need 4096
8992005-08-31 22:09:34.397 Broadcasting free space avail
9002005-08-31 22:09:34.397 WriteAudio: Preparing 4096 bytes (1024 frames)
9012005-08-31 22:09:34.397 audio waiting for space on soundcard: have 1624 need 4096
9022005-08-31 22:09:34.409 audio waiting for space on soundcard: have 3936 need 4096
9032005-08-31 22:09:34.413 _AddSamples bytes=4608, used=176129, free=591871, timecode=13681022
9042005-08-31 22:09:34.421 Broadcasting free space avail
9052005-08-31 22:09:34.421 WriteAudio: Preparing 4096 bytes (1024 frames)
9062005-08-31 22:09:34.422 audio waiting for space on soundcard: have 2208 need 4096
9072005-08-31 22:09:34.433 Broadcasting free space avail
9082005-08-31 22:09:34.434 WriteAudio: Preparing 4096 bytes (1024 frames)
9092005-08-31 22:09:34.434 audio waiting for space on soundcard: have 444 need 4096
9102005-08-31 22:09:34.446 audio waiting for space on soundcard: have 2756 need 4096
9112005-08-31 22:09:34.449 positionMap[ 14 ] == 9380636.
9122005-08-31 22:09:34.453 _AddSamples bytes=4608, used=172545, free=595455, timecode=13681046
9132005-08-31 22:09:34.454 _AddSamples bytes=4608, used=177153, free=590847, timecode=13681070
9142005-08-31 22:09:34.458 Broadcasting free space avail
9152005-08-31 22:09:34.458 WriteAudio: Preparing 4096 bytes (1024 frames)
9162005-08-31 22:09:34.458 audio waiting for space on soundcard: have 1008 need 4096
9172005-08-31 22:09:34.470 audio waiting for space on soundcard: have 3316 need 4096
9182005-08-31 22:09:34.486 Broadcasting free space avail
9192005-08-31 22:09:34.486 WriteAudio: Preparing 4096 bytes (1024 frames)
9202005-08-31 22:09:34.487 audio waiting for space on soundcard: have 2400 need 4096
9212005-08-31 22:09:34.492 _AddSamples bytes=4608, used=173569, free=594431, timecode=13681094
9222005-08-31 22:09:34.492 _AddSamples bytes=4608, used=178177, free=589823, timecode=13681118
9232005-08-31 22:09:34.498 Broadcasting free space avail
9242005-08-31 22:09:34.498 WriteAudio: Preparing 4096 bytes (1024 frames)
9252005-08-31 22:09:34.499 audio waiting for space on soundcard: have 620 need 4096
9262005-08-31 22:09:34.511 audio waiting for space on soundcard: have 2916 need 4096
9272005-08-31 22:09:34.523 Broadcasting free space avail
9282005-08-31 22:09:34.523 WriteAudio: Preparing 4096 bytes (1024 frames)
9292005-08-31 22:09:34.523 audio waiting for space on soundcard: have 1224 need 4096
930'video_output' mean = '32238.02', std. dev. = '49833.19', fps = '31.02'
9312005-08-31 22:09:34.532 _AddSamples bytes=4608, used=174593, free=593407, timecode=13681142
9322005-08-31 22:09:34.535 audio waiting for space on soundcard: have 3504 need 4096
9332005-08-31 22:09:34.547 Broadcasting free space avail
9342005-08-31 22:09:34.547 WriteAudio: Preparing 4096 bytes (1024 frames)
9352005-08-31 22:09:34.548 audio waiting for space on soundcard: have 1844 need 4096
9362005-08-31 22:09:34.559 Broadcasting free space avail
9372005-08-31 22:09:34.560 WriteAudio: Preparing 4096 bytes (1024 frames)
9382005-08-31 22:09:34.560 audio waiting for space on soundcard: have 64 need 4096
9392005-08-31 22:09:34.572 audio waiting for space on soundcard: have 2356 need 4096
9402005-08-31 22:09:34.572 _AddSamples bytes=4608, used=171009, free=596991, timecode=13681166
9412005-08-31 22:09:34.573 _AddSamples bytes=4608, used=175617, free=592383, timecode=13681190
9422005-08-31 22:09:34.584 Broadcasting free space avail
9432005-08-31 22:09:34.584 WriteAudio: Preparing 4096 bytes (1024 frames)
9442005-08-31 22:09:34.584 audio waiting for space on soundcard: have 672 need 4096
9452005-08-31 22:09:34.596 audio waiting for space on soundcard: have 2948 need 4096
9462005-08-31 22:09:34.610 Broadcasting free space avail
9472005-08-31 22:09:34.610 WriteAudio: Preparing 4096 bytes (1024 frames)
9482005-08-31 22:09:34.610 audio waiting for space on soundcard: have 1528 need 4096
9492005-08-31 22:09:34.613 _AddSamples bytes=4608, used=172033, free=595967, timecode=13681214
9502005-08-31 22:09:34.613 _AddSamples bytes=4608, used=176641, free=591359, timecode=13681238
9512005-08-31 22:09:34.621 audio waiting for space on soundcard: have 3548 need 4096
9522005-08-31 22:09:34.633 Broadcasting free space avail
9532005-08-31 22:09:34.633 WriteAudio: Preparing 4096 bytes (1024 frames)
9542005-08-31 22:09:34.633 audio waiting for space on soundcard: have 1860 need 4096
9552005-08-31 22:09:34.634 read  <- 18 6       128000
9562005-08-31 22:09:34.634 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
9572005-08-31 22:09:34.634 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
9582005-08-31 22:09:34.645 Broadcasting free space avail
9592005-08-31 22:09:34.645 WriteAudio: Preparing 4096 bytes (1024 frames)
9602005-08-31 22:09:34.645 audio waiting for space on soundcard: have 124 need 4096
9612005-08-31 22:09:34.654 _AddSamples bytes=4608, used=173057, free=594943, timecode=13681262
9622005-08-31 22:09:34.657 audio waiting for space on soundcard: have 2384 need 4096
9632005-08-31 22:09:34.669 Broadcasting free space avail
9642005-08-31 22:09:34.669 WriteAudio: Preparing 4096 bytes (1024 frames)
9652005-08-31 22:09:34.670 audio waiting for space on soundcard: have 676 need 4096
9662005-08-31 22:09:34.681 audio waiting for space on soundcard: have 2956 need 4096
9672005-08-31 22:09:34.693 Broadcasting free space avail
9682005-08-31 22:09:34.694 WriteAudio: Preparing 4096 bytes (1024 frames)
9692005-08-31 22:09:34.694 audio waiting for space on soundcard: have 1280 need 4096
9702005-08-31 22:09:34.694 _AddSamples bytes=4608, used=169473, free=598527, timecode=13681286
9712005-08-31 22:09:34.695 _AddSamples bytes=4608, used=174081, free=593919, timecode=13681310
9722005-08-31 22:09:34.708 audio waiting for space on soundcard: have 4008 need 4096
9732005-08-31 22:09:34.722 Broadcasting free space avail
9742005-08-31 22:09:34.722 WriteAudio: Preparing 4096 bytes (1024 frames)
9752005-08-31 22:09:34.722 audio waiting for space on soundcard: have 2536 need 4096
9762005-08-31 22:09:34.734 _AddSamples bytes=4608, used=174593, free=593407, timecode=13681334
9772005-08-31 22:09:34.735 _AddSamples bytes=4608, used=179201, free=588799, timecode=13681358
9782005-08-31 22:09:34.735 Broadcasting free space avail
9792005-08-31 22:09:34.735 WriteAudio: Preparing 4096 bytes (1024 frames)
9802005-08-31 22:09:34.735 audio waiting for space on soundcard: have 1048 need 4096
9812005-08-31 22:09:34.750 audio waiting for space on soundcard: have 3812 need 4096
9822005-08-31 22:09:34.762 Broadcasting free space avail
9832005-08-31 22:09:34.762 WriteAudio: Preparing 4096 bytes (1024 frames)
9842005-08-31 22:09:34.762 audio waiting for space on soundcard: have 2132 need 4096
9852005-08-31 22:09:34.774 _AddSamples bytes=4608, used=175617, free=592383, timecode=13681382
9862005-08-31 22:09:34.778 Broadcasting free space avail
9872005-08-31 22:09:34.778 WriteAudio: Preparing 4096 bytes (1024 frames)
9882005-08-31 22:09:34.778 audio waiting for space on soundcard: have 1088 need 4096
9892005-08-31 22:09:34.794 Broadcasting free space avail
9902005-08-31 22:09:34.794 WriteAudio: Preparing 4096 bytes (1024 frames)
9912005-08-31 22:09:34.795 audio waiting for space on soundcard: have 112 need 4096
9922005-08-31 22:09:34.807 audio waiting for space on soundcard: have 2416 need 4096
9932005-08-31 22:09:34.814 _AddSamples bytes=4608, used=172033, free=595967, timecode=13681406
9942005-08-31 22:09:34.815 _AddSamples bytes=4608, used=176641, free=591359, timecode=13681430
9952005-08-31 22:09:34.818 Broadcasting free space avail
9962005-08-31 22:09:34.818 WriteAudio: Preparing 4096 bytes (1024 frames)
9972005-08-31 22:09:34.819 audio waiting for space on soundcard: have 624 need 4096
9982005-08-31 22:09:34.831 audio waiting for space on soundcard: have 2908 need 4096
9992005-08-31 22:09:34.843 Broadcasting free space avail
10002005-08-31 22:09:34.843 WriteAudio: Preparing 4096 bytes (1024 frames)
10012005-08-31 22:09:34.843 audio waiting for space on soundcard: have 1172 need 4096
10022005-08-31 22:09:34.854 _AddSamples bytes=4608, used=173057, free=594943, timecode=13681454
10032005-08-31 22:09:34.855 _AddSamples bytes=4608, used=177665, free=590335, timecode=13681478
10042005-08-31 22:09:34.856 audio waiting for space on soundcard: have 3628 need 4096
10052005-08-31 22:09:34.867 Broadcasting free space avail
10062005-08-31 22:09:34.867 WriteAudio: Preparing 4096 bytes (1024 frames)
10072005-08-31 22:09:34.867 audio waiting for space on soundcard: have 1712 need 4096
10082005-08-31 22:09:34.879 audio waiting for space on soundcard: have 4012 need 4096
10092005-08-31 22:09:34.891 Broadcasting free space avail
10102005-08-31 22:09:34.891 WriteAudio: Preparing 4096 bytes (1024 frames)
10112005-08-31 22:09:34.891 audio waiting for space on soundcard: have 2268 need 4096
10122005-08-31 22:09:34.906 Broadcasting free space avail
10132005-08-31 22:09:34.906 WriteAudio: Preparing 4096 bytes (1024 frames)
10142005-08-31 22:09:34.906 audio waiting for space on soundcard: have 992 need 4096
10152005-08-31 22:09:34.911 _AddSamples bytes=4608, used=169985, free=598015, timecode=13681502
10162005-08-31 22:09:34.915 audio waiting for space on soundcard: have 2752 need 4096
10172005-08-31 22:09:34.928 Broadcasting free space avail
10182005-08-31 22:09:34.928 WriteAudio: Preparing 4096 bytes (1024 frames)
10192005-08-31 22:09:34.928 audio waiting for space on soundcard: have 1108 need 4096
10202005-08-31 22:09:34.929 positionMap[ 15 ] == 9548332.
10212005-08-31 22:09:34.934 _AddSamples bytes=4608, used=170497, free=597503, timecode=13681526
10222005-08-31 22:09:34.934 _AddSamples bytes=4608, used=175105, free=592895, timecode=13681550
10232005-08-31 22:09:34.939 audio waiting for space on soundcard: have 3304 need 4096
10242005-08-31 22:09:34.951 Broadcasting free space avail
10252005-08-31 22:09:34.952 WriteAudio: Preparing 4096 bytes (1024 frames)
10262005-08-31 22:09:34.952 audio waiting for space on soundcard: have 1616 need 4096
10272005-08-31 22:09:34.963 audio waiting for space on soundcard: have 3856 need 4096
10282005-08-31 22:09:34.972 _AddSamples bytes=4608, used=175617, free=592383, timecode=13681574
10292005-08-31 22:09:34.973 _AddSamples bytes=4608, used=180225, free=587775, timecode=13681598
10302005-08-31 22:09:34.976 Broadcasting free space avail
10312005-08-31 22:09:34.976 WriteAudio: Preparing 4096 bytes (1024 frames)
10322005-08-31 22:09:34.976 audio waiting for space on soundcard: have 2124 need 4096
10332005-08-31 22:09:34.988 Broadcasting free space avail
10342005-08-31 22:09:34.988 WriteAudio: Preparing 4096 bytes (1024 frames)
10352005-08-31 22:09:34.988 audio waiting for space on soundcard: have 368 need 4096
10362005-08-31 22:09:35.000 audio waiting for space on soundcard: have 2624 need 4096
10372005-08-31 22:09:35.012 Broadcasting free space avail
10382005-08-31 22:09:35.012 WriteAudio: Preparing 4096 bytes (1024 frames)
10392005-08-31 22:09:35.012 _AddSamples bytes=4608, used=172545, free=595455, timecode=13681622
10402005-08-31 22:09:35.012 audio waiting for space on soundcard: have 924 need 4096
10412005-08-31 22:09:35.024 audio waiting for space on soundcard: have 3244 need 4096
10422005-08-31 22:09:35.036 Broadcasting free space avail
10432005-08-31 22:09:35.036 WriteAudio: Preparing 4096 bytes (1024 frames)
10442005-08-31 22:09:35.036 audio waiting for space on soundcard: have 1472 need 4096
10452005-08-31 22:09:35.049 audio waiting for space on soundcard: have 3932 need 4096
10462005-08-31 22:09:35.052 _AddSamples bytes=4608, used=173057, free=594943, timecode=13681646
10472005-08-31 22:09:35.053 _AddSamples bytes=4608, used=177665, free=590335, timecode=13681670
10482005-08-31 22:09:35.060 Broadcasting free space avail
10492005-08-31 22:09:35.060 WriteAudio: Preparing 4096 bytes (1024 frames)
10502005-08-31 22:09:35.061 audio waiting for space on soundcard: have 2016 need 4096
10512005-08-31 22:09:35.073 Broadcasting free space avail
10522005-08-31 22:09:35.073 WriteAudio: Preparing 4096 bytes (1024 frames)
10532005-08-31 22:09:35.073 audio waiting for space on soundcard: have 296 need 4096
10542005-08-31 22:09:35.081 read  <- 18 6       128000
10552005-08-31 22:09:35.082 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
10562005-08-31 22:09:35.082 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
10572005-08-31 22:09:35.085 audio waiting for space on soundcard: have 2544 need 4096
10582005-08-31 22:09:35.093 _AddSamples bytes=4608, used=174081, free=593919, timecode=13681694
10592005-08-31 22:09:35.094 _AddSamples bytes=4608, used=178689, free=589311, timecode=13681718
10602005-08-31 22:09:35.097 Broadcasting free space avail
10612005-08-31 22:09:35.097 WriteAudio: Preparing 4096 bytes (1024 frames)
10622005-08-31 22:09:35.097 audio waiting for space on soundcard: have 808 need 4096
10632005-08-31 22:09:35.109 audio waiting for space on soundcard: have 3096 need 4096
10642005-08-31 22:09:35.121 Broadcasting free space avail
10652005-08-31 22:09:35.121 WriteAudio: Preparing 4096 bytes (1024 frames)
10662005-08-31 22:09:35.121 audio waiting for space on soundcard: have 1392 need 4096
10672005-08-31 22:09:35.132 _AddSamples bytes=4608, used=175105, free=592895, timecode=13681742
10682005-08-31 22:09:35.133 audio waiting for space on soundcard: have 3656 need 4096
10692005-08-31 22:09:35.145 Broadcasting free space avail
10702005-08-31 22:09:35.145 WriteAudio: Preparing 4096 bytes (1024 frames)
10712005-08-31 22:09:35.146 audio waiting for space on soundcard: have 1948 need 4096
10722005-08-31 22:09:35.157 Broadcasting free space avail
10732005-08-31 22:09:35.158 WriteAudio: Preparing 4096 bytes (1024 frames)
10742005-08-31 22:09:35.158 audio waiting for space on soundcard: have 216 need 4096
10752005-08-31 22:09:35.173 _AddSamples bytes=4608, used=171521, free=596479, timecode=13681766
10762005-08-31 22:09:35.174 audio waiting for space on soundcard: have 3252 need 4096
10772005-08-31 22:09:35.174 _AddSamples bytes=4608, used=176129, free=591871, timecode=13681790
10782005-08-31 22:09:35.186 Broadcasting free space avail
10792005-08-31 22:09:35.186 WriteAudio: Preparing 4096 bytes (1024 frames)
10802005-08-31 22:09:35.186 audio waiting for space on soundcard: have 1548 need 4096
10812005-08-31 22:09:35.198 audio waiting for space on soundcard: have 3808 need 4096
10822005-08-31 22:09:35.210 Broadcasting free space avail
10832005-08-31 22:09:35.210 WriteAudio: Preparing 4096 bytes (1024 frames)
10842005-08-31 22:09:35.211 audio waiting for space on soundcard: have 2156 need 4096
10852005-08-31 22:09:35.212 _AddSamples bytes=4608, used=172545, free=595455, timecode=13681814
10862005-08-31 22:09:35.213 _AddSamples bytes=4608, used=177153, free=590847, timecode=13681838
10872005-08-31 22:09:35.226 Broadcasting free space avail
10882005-08-31 22:09:35.226 WriteAudio: Preparing 4096 bytes (1024 frames)
10892005-08-31 22:09:35.227 audio waiting for space on soundcard: have 1140 need 4096
10902005-08-31 22:09:35.238 audio waiting for space on soundcard: have 3416 need 4096
10912005-08-31 22:09:35.253 _AddSamples bytes=4608, used=177665, free=590335, timecode=13681862
10922005-08-31 22:09:35.254 Broadcasting free space avail
10932005-08-31 22:09:35.254 WriteAudio: Preparing 4096 bytes (1024 frames)
10942005-08-31 22:09:35.254 audio waiting for space on soundcard: have 2284 need 4096
10952005-08-31 22:09:35.263 audio waiting for space on soundcard: have 3948 need 4096
10962005-08-31 22:09:35.275 Broadcasting free space avail
10972005-08-31 22:09:35.275 WriteAudio: Preparing 4096 bytes (1024 frames)
10982005-08-31 22:09:35.275 audio waiting for space on soundcard: have 2236 need 4096
10992005-08-31 22:09:35.287 Broadcasting free space avail
11002005-08-31 22:09:35.287 WriteAudio: Preparing 4096 bytes (1024 frames)
11012005-08-31 22:09:35.287 audio waiting for space on soundcard: have 468 need 4096
11022005-08-31 22:09:35.291 _AddSamples bytes=4608, used=169985, free=598015, timecode=13681886
11032005-08-31 22:09:35.292 _AddSamples bytes=4608, used=174593, free=593407, timecode=13681910
11042005-08-31 22:09:35.299 audio waiting for space on soundcard: have 2704 need 4096
11052005-08-31 22:09:35.311 Broadcasting free space avail
11062005-08-31 22:09:35.311 WriteAudio: Preparing 4096 bytes (1024 frames)
11072005-08-31 22:09:35.311 audio waiting for space on soundcard: have 1008 need 4096
11082005-08-31 22:09:35.323 audio waiting for space on soundcard: have 3288 need 4096
11092005-08-31 22:09:35.332 _AddSamples bytes=4608, used=175105, free=592895, timecode=13681934
11102005-08-31 22:09:35.332 _AddSamples bytes=4608, used=179713, free=588287, timecode=13681958
11112005-08-31 22:09:35.335 Broadcasting free space avail
11122005-08-31 22:09:35.336 WriteAudio: Preparing 4096 bytes (1024 frames)
11132005-08-31 22:09:35.336 audio waiting for space on soundcard: have 1584 need 4096
11142005-08-31 22:09:35.347 audio waiting for space on soundcard: have 3848 need 4096
11152005-08-31 22:09:35.360 Broadcasting free space avail
11162005-08-31 22:09:35.360 WriteAudio: Preparing 4096 bytes (1024 frames)
11172005-08-31 22:09:35.360 audio waiting for space on soundcard: have 2148 need 4096
11182005-08-31 22:09:35.372 _AddSamples bytes=4608, used=176129, free=591871, timecode=13681982
11192005-08-31 22:09:35.372 Broadcasting free space avail
11202005-08-31 22:09:35.372 WriteAudio: Preparing 4096 bytes (1024 frames)
11212005-08-31 22:09:35.372 audio waiting for space on soundcard: have 368 need 4096
11222005-08-31 22:09:35.384 audio waiting for space on soundcard: have 2716 need 4096
11232005-08-31 22:09:35.396 Broadcasting free space avail
11242005-08-31 22:09:35.396 WriteAudio: Preparing 4096 bytes (1024 frames)
11252005-08-31 22:09:35.397 audio waiting for space on soundcard: have 996 need 4096
11262005-08-31 22:09:35.408 positionMap[ 16 ] == 9707380.
11272005-08-31 22:09:35.413 audio waiting for space on soundcard: have 4088 need 4096
11282005-08-31 22:09:35.413 _AddSamples bytes=4608, used=172545, free=595455, timecode=13682006
11292005-08-31 22:09:35.414 _AddSamples bytes=4608, used=177153, free=590847, timecode=13682030
11302005-08-31 22:09:35.425 Broadcasting free space avail
11312005-08-31 22:09:35.425 WriteAudio: Preparing 4096 bytes (1024 frames)
11322005-08-31 22:09:35.425 audio waiting for space on soundcard: have 2408 need 4096
11332005-08-31 22:09:35.434 read  <- 18 6       128000
11342005-08-31 22:09:35.434 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
11352005-08-31 22:09:35.434 write -> 18 53      QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RINGBUF[]:[]128000
11362005-08-31 22:09:35.437 Broadcasting free space avail
11372005-08-31 22:09:35.437 WriteAudio: Preparing 4096 bytes (1024 frames)
11382005-08-31 22:09:35.437 audio waiting for space on soundcard: have 640 need 4096
11392005-08-31 22:09:35.450 audio waiting for space on soundcard: have 2984 need 4096
11402005-08-31 22:09:35.453 _AddSamples bytes=4608, used=173569, free=594431, timecode=13682054
11412005-08-31 22:09:35.454 _AddSamples bytes=4608, used=178177, free=589823, timecode=13682078
11422005-08-31 22:09:35.461 Broadcasting free space avail
11432005-08-31 22:09:35.462 WriteAudio: Preparing 4096 bytes (1024 frames)
11442005-08-31 22:09:35.462 audio waiting for space on soundcard: have 1216 need 4096
11452005-08-31 22:09:35.473 audio waiting for space on soundcard: have 3464 need 4096
11462005-08-31 22:09:35.485 Broadcasting free space avail
11472005-08-31 22:09:35.486 WriteAudio: Preparing 4096 bytes (1024 frames)
11482005-08-31 22:09:35.486 audio waiting for space on soundcard: have 1760 need 4096
11492005-08-31 22:09:35.493 _AddSamples bytes=4608, used=174593, free=593407, timecode=13682102
11502005-08-31 22:09:35.498 audio waiting for space on soundcard: have 3992 need 4096
11512005-08-31 22:09:35.510 Broadcasting free space avail
11522005-08-31 22:09:35.510 WriteAudio: Preparing 4096 bytes (1024 frames)
11532005-08-31 22:09:35.510 audio waiting for space on soundcard: have 2284 need 4096
11542005-08-31 22:09:35.522 Broadcasting free space avail
11552005-08-31 22:09:35.522 WriteAudio: Preparing 4096 bytes (1024 frames)
11562005-08-31 22:09:35.522 audio waiting for space on soundcard: have 476 need 4096
11572005-08-31 22:09:35.533 _AddSamples bytes=4608, used=171009, free=596991, timecode=13682126
11582005-08-31 22:09:35.534 _AddSamples bytes=4608, used=175617, free=592383, timecode=13682150
11592005-08-31 22:09:35.534 audio waiting for space on soundcard: have 2728 need 4096
11602005-08-31 22:09:35.548 Broadcasting free space avail
11612005-08-31 22:09:35.548 WriteAudio: Preparing 4096 bytes (1024 frames)
11622005-08-31 22:09:35.549 audio waiting for space on soundcard: have 1500 need 4096
11632005-08-31 22:09:35.562 audio waiting for space on soundcard: have 4008 need 4096
11642005-08-31 22:09:35.573 _AddSamples bytes=4608, used=176129, free=591871, timecode=13682174
11652005-08-31 22:09:35.574 _AddSamples bytes=4608, used=180737, free=587263, timecode=13682198
11662005-08-31 22:09:35.574 Broadcasting free space avail
11672005-08-31 22:09:35.574 WriteAudio: Preparing 4096 bytes (1024 frames)
11682005-08-31 22:09:35.574 audio waiting for space on soundcard: have 2376 need 4096
11692005-08-31 22:09:35.586 Attempting to change from WatchingLiveTV to None
11702005-08-31 22:09:35.586 TV::StopStuff() -- begin
11712005-08-31 22:09:35.586 TV::StopStuff(): stopping ring buffer[s]
11722005-08-31 22:09:35.590 Broadcasting free space avail
11732005-08-31 22:09:35.590 WriteAudio: Preparing 4096 bytes (1024 frames)
11742005-08-31 22:09:35.590 audio waiting for space on soundcard: have 1244 need 4096
11752005-08-31 22:09:35.602 audio waiting for space on soundcard: have 3496 need 4096
11762005-08-31 22:09:35.613 _AddSamples bytes=4608, used=177153, free=590847, timecode=13682222
11772005-08-31 22:09:35.614 Broadcasting free space avail
11782005-08-31 22:09:35.614 WriteAudio: Preparing 4096 bytes (1024 frames)
11792005-08-31 22:09:35.614 audio waiting for space on soundcard: have 1776 need 4096
11802005-08-31 22:09:35.626 audio waiting for space on soundcard: have 4008 need 4096
11812005-08-31 22:09:35.638 Broadcasting free space avail
11822005-08-31 22:09:35.638 WriteAudio: Preparing 4096 bytes (1024 frames)
11832005-08-31 22:09:35.638 audio waiting for space on soundcard: have 2264 need 4096
11842005-08-31 22:09:35.650 Broadcasting free space avail
11852005-08-31 22:09:35.650 WriteAudio: Preparing 4096 bytes (1024 frames)
11862005-08-31 22:09:35.650 audio waiting for space on soundcard: have 544 need 4096
11872005-08-31 22:09:35.653 _AddSamples bytes=4608, used=169473, free=598527, timecode=13682246
11882005-08-31 22:09:35.653 _AddSamples bytes=4608, used=174081, free=593919, timecode=13682270
11892005-08-31 22:09:35.666 audio waiting for space on soundcard: have 3496 need 4096
11902005-08-31 22:09:35.678 Broadcasting free space avail
11912005-08-31 22:09:35.678 WriteAudio: Preparing 4096 bytes (1024 frames)
11922005-08-31 22:09:35.678 audio waiting for space on soundcard: have 1808 need 4096
11932005-08-31 22:09:35.690 audio waiting for space on soundcard: have 4012 need 4096
11942005-08-31 22:09:35.693 _AddSamples bytes=4608, used=174593, free=593407, timecode=13682294
11952005-08-31 22:09:35.693 _AddSamples bytes=4608, used=179201, free=588799, timecode=13682318
11962005-08-31 22:09:35.702 Broadcasting free space avail
11972005-08-31 22:09:35.702 WriteAudio: Preparing 4096 bytes (1024 frames)
11982005-08-31 22:09:35.702 audio waiting for space on soundcard: have 2276 need 4096
11992005-08-31 22:09:35.714 Broadcasting free space avail
12002005-08-31 22:09:35.714 WriteAudio: Preparing 4096 bytes (1024 frames)
12012005-08-31 22:09:35.714 audio waiting for space on soundcard: have 488 need 4096
12022005-08-31 22:09:35.726 audio waiting for space on soundcard: have 2740 need 4096
12032005-08-31 22:09:35.733 _AddSamples bytes=4608, used=175617, free=592383, timecode=13682342
12042005-08-31 22:09:35.738 Broadcasting free space avail
12052005-08-31 22:09:35.738 WriteAudio: Preparing 4096 bytes (1024 frames)
12062005-08-31 22:09:35.738 audio waiting for space on soundcard: have 1000 need 4096
12072005-08-31 22:09:35.750 audio waiting for space on soundcard: have 3244 need 4096
12082005-08-31 22:09:35.762 Broadcasting free space avail
12092005-08-31 22:09:35.762 WriteAudio: Preparing 4096 bytes (1024 frames)
12102005-08-31 22:09:35.762 audio waiting for space on soundcard: have 1544 need 4096
12112005-08-31 22:09:35.772 _AddSamples bytes=4608, used=172033, free=595967, timecode=13682366
12122005-08-31 22:09:35.772 _AddSamples bytes=4608, used=176641, free=591359, timecode=13682390
12132005-08-31 22:09:35.774 audio waiting for space on soundcard: have 3840 need 4096
12142005-08-31 22:09:35.786 Broadcasting free space avail
12152005-08-31 22:09:35.786 WriteAudio: Preparing 4096 bytes (1024 frames)
12162005-08-31 22:09:35.787 audio waiting for space on soundcard: have 2144 need 4096
12172005-08-31 22:09:35.789 read  <- 18 6       128000
12182005-08-31 22:09:35.789 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
12192005-08-31 22:09:35.789 TV::StopStuff(): stopping player[s] (1/2)
12202005-08-31 22:09:35.789 TV::StopStuff(): stopping recorder[s]
12212005-08-31 22:09:35.789 write -> 17 32      QUERY_RECORDER 1[]:[]STOP_LIVETV
12222005-08-31 22:09:35.798 Broadcasting free space avail
12232005-08-31 22:09:35.799 WriteAudio: Preparing 4096 bytes (1024 frames)
12242005-08-31 22:09:35.799 audio waiting for space on soundcard: have 372 need 4096
12252005-08-31 22:09:35.808 ~VideoOutputXv()
12262005-08-31 22:09:35.808 VideoBuffers::DiscardFrames(): LUUUUUUAUUUUUUUUUUUUUUUUUUUUUUU
12272005-08-31 22:09:35.808 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
12282005-08-31 22:09:35.808 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
12292005-08-31 22:09:35.809 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
12302005-08-31 22:09:35.818 read  <- 16 48      BACKEND_MESSAGE[]:[]DONE_RECORDING 1 4[]:[]empty
12312005-08-31 22:09:35.819 Broadcasting free space avail
12322005-08-31 22:09:35.820 WriteAudio: Preparing 4096 bytes (1024 frames)
12332005-08-31 22:09:35.821 audio waiting for space on soundcard: have 504 need 4096
12342005-08-31 22:09:35.822 Killing AudioOutputDSP
12352005-08-31 22:09:35.831 OutputAudioLoop: Stop Event
12362005-08-31 22:09:35.831 kickoffOutputAudioLoop exiting
12372005-08-31 22:09:35.875 read  <- 17 2       ok
12382005-08-31 22:09:35.875 TV::StopStuff(): stopping player[s] (2/2)
12392005-08-31 22:09:35.884 write -> 18 33      QUERY_RECORDER 1[]:[]DONE_RINGBUF
12402005-08-31 22:09:35.891 read  <- 16 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
12412005-08-31 22:09:35.899 read  <- 16 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
12422005-08-31 22:09:35.899 read  <- 18 2       OK
12432005-08-31 22:09:35.900 TV::StopStuff() -- end
12442005-08-31 22:09:35.900 Changing from WatchingLiveTV to None
12452005-08-31 22:09:35.907 Attempting to change from None to None
12462005-08-31 22:09:44.387 write -> 14 34      QUERY_IS_ACTIVE_BACKEND[]:[]vdrtv1
12472005-08-31 22:09:44.406 read  <- 14 4       TRUE
1248Destroying SipFsm object