2009-10-24 22:07:03.765 AO: Broadcasting free space avail
2009-10-24 22:07:03.783 MythSocket(5727c60:49): readBlock(0x97077808, 425984) called
2009-10-24 22:07:03.784 MythSocket(5727c60:49): readBlock(0x97082152, 421640) called
2009-10-24 22:07:03.784 MythSocket(5727c60:49): readBlock(0x97095184, 408608) called
2009-10-24 22:07:03.784 MythSocket(5727c60:49): readBlock(0x97103872, 399920) called
2009-10-24 22:07:03.784 MythSocket(5727c60:49): readBlock(0x97125592, 378200) called
2009-10-24 22:07:03.784 AVSync show
2009-10-24 22:07:03.784 MythSocket(5727c60:49): readBlock(0x97129936, 373856) called
2009-10-24 22:07:03.784 MythSocket(5727c60:49): readBlock(0x97138624, 365168) called
2009-10-24 22:07:03.785 MythSocket(5727c60:49): readBlock(0x97145864, 357928) called
2009-10-24 22:07:03.785 MythSocket(5727c60:49): readBlock(0x97158896, 344896) called
2009-10-24 22:07:03.785 MythSocket(5727c60:49): readBlock(0x97174824, 328968) called
2009-10-24 22:07:03.785 MythSocket(5727c60:49): readBlock(0x97205232, 298560) called
2009-10-24 22:07:03.786 MythSocket(5727c60:49): readBlock(0x97215368, 288424) called
2009-10-24 22:07:03.786 MythSocket(5727c60:49): readBlock(0x97231296, 272496) called
2009-10-24 22:07:03.786 MythSocket(5727c60:49): readBlock(0x97241432, 262360) called
2009-10-24 22:07:03.786 MythSocket(5727c60:49): readBlock(0x97261704, 242088) called
2009-10-24 22:07:03.787 MythSocket(5727c60:49): readBlock(0x97295008, 208784) called
2009-10-24 22:07:03.787 MythSocket(5727c60:49): readBlock(0x97308040, 195752) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97310936, 192856) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97328312, 175480) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97332656, 171136) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97339896, 163896) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97363064, 140728) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97368856, 134936) called
2009-10-24 22:07:03.788 MythSocket(5727c60:49): readBlock(0x97384784, 119008) called
2009-10-24 22:07:03.789 MythSocket(5727c60:49): readBlock(0x97390576, 113216) called
2009-10-24 22:07:03.789 MythSocket(5727c60:49): readBlock(0x97396368, 107424) called
2009-10-24 22:07:03.789 MythSocket(5727c60:49): readBlock(0x97410848, 92944) called
2009-10-24 22:07:03.789 MythSocket(5727c60:49): readBlock(0x97420984, 82808) called
2009-10-24 22:07:03.789 MythSocket(5727c60:49): readBlock(0x97426776, 77016) called
2009-10-24 22:07:03.789 MythSocket(5727c60:49): readBlock(0x97447048, 56744) called
2009-10-24 22:07:03.790 MythSocket(5727c60:49): readBlock(0x97452840, 50952) called
2009-10-24 22:07:03.790 MythSocket(5727c60:49): readBlock(0x97454288, 49504) called
2009-10-24 22:07:03.790 MythSocket(5727c60:49): readBlock(0x97470216, 33576) called
2009-10-24 22:07:03.790 MythSocket(5727c60:49): readBlock(0x97477456, 26336) called
2009-10-24 22:07:03.790 MythSocket(5727c60:49): readBlock(0x97487592, 16200) called
2009-10-24 22:07:03.790 MythSocket(5729980:48): readBlock(0x118033960, 8) called
2009-10-24 22:07:03.790 MythSocket(5729980:48): readBlock(0x133217432, 6) called
2009-10-24 22:07:03.790 MythSocket(5729980:48): read  <- 48 6       425984
2009-10-24 22:07:03.790 Got data on select
2009-10-24 22:07:03.791 Processing ready reads
2009-10-24 22:07:03.791 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:03.791 Reacquired ready read lock
2009-10-24 22:07:03.791 ProcessAddRemoveQueues
2009-10-24 22:07:03.791 Construct FD_SET
2009-10-24 22:07:03.791 Waiting on select..
2009-10-24 22:07:03.791 Read(): reqd=425984, rcvd=425984, rept=425984, error=0
2009-10-24 22:07:03.792 NVP(0): A/V Divergence: 0.818588, Rate: -0.053078, Warpfactor: 1.00658, warpfactor_avg: 1.00004
2009-10-24 22:07:03.792 AVSync waitforframe 0 0
2009-10-24 22:07:03.796 AO: Broadcasting free space avail
2009-10-24 22:07:03.800 AFD: video timecode 4030879549 4030879549 44787550 44787517
2009-10-24 22:07:03.801 AO: _AddSamples samples=1536 bytes=6144, used=147457, free=1388543, timecode=44787639 needsupmix 0
2009-10-24 22:07:03.815 AVSync show
2009-10-24 22:07:03.818 NVP(0): A/V Divergence: 0.831205, Rate: 0.0126176, Warpfactor: 1.00679, warpfactor_avg: 1.00004
2009-10-24 22:07:03.818 AVSync waitforframe 0 0
2009-10-24 22:07:03.822 AFD: video timecode 4030882552 4030882552 44787583 44787550
2009-10-24 22:07:03.822 AO: _AddSamples samples=1536 bytes=6144, used=153601, free=1382399, timecode=44787671 needsupmix 0
2009-10-24 22:07:03.828 AO: Broadcasting free space avail
2009-10-24 22:07:03.848 AVSync show
2009-10-24 22:07:03.851 NVP(0): A/V Divergence: 0.848169, Rate: 0.0169634, Warpfactor: 1.00696, warpfactor_avg: 1.00004
2009-10-24 22:07:03.851 AVSync waitforframe 0 0
2009-10-24 22:07:03.855 AFD: video timecode 4030894564 4030885555 44787617 44787583
2009-10-24 22:07:03.856 AO: _AddSamples samples=1536 bytes=6144, used=153601, free=1382399, timecode=44787703 needsupmix 0
2009-10-24 22:07:03.859 AO: Broadcasting free space avail
2009-10-24 22:07:03.882 AVSync show
2009-10-24 22:07:03.885 NVP(0): A/V Divergence: 0.853414, Rate: 0.00524486, Warpfactor: 1.00691, warpfactor_avg: 1.00004
2009-10-24 22:07:03.885 AVSync waitforframe 0 0
2009-10-24 22:07:03.888 AFD: video timecode 4030888558 4030888558 44787650 44787617
2009-10-24 22:07:03.889 AO: _AddSamples samples=1536 bytes=6144, used=153601, free=1382399, timecode=44787735 needsupmix 0
2009-10-24 22:07:03.894 AO: Broadcasting free space avail
2009-10-24 22:07:03.915 AVSync show
2009-10-24 22:07:03.917 TV: DoNVPSeek() -- begin
2009-10-24 22:07:03.917 Setting Master volume to 0
2009-10-24 22:07:03.917 Volume range is 0 to 31, mult=3.22581
2009-10-24 22:07:03.918 channel 0 vol set to 0
2009-10-24 22:07:03.918 Volume range is 0 to 31, mult=3.22581
2009-10-24 22:07:03.918 Mixer unable to find control Master 1
2009-10-24 22:07:03.918 ALSA: channel 0 playback switch set to 1
2009-10-24 22:07:03.918 Setting Master volume to 0
2009-10-24 22:07:03.918 mixer unable to find control Master 1
2009-10-24 22:07:03.918 TV: DoNVPSeek() -- ff
2009-10-24 22:07:03.918 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:03.918 MythSocket(57352c0:50): writeBlock(0x140644442957688, 47)
2009-10-24 22:07:03.918 MythSocket(57352c0:50): readBlock(0x140644409284616, 8) called
2009-10-24 22:07:03.918 MythSocket(57352c0:50): readBlock(0x140644409278568, 10) called
2009-10-24 22:07:03.918 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6662
2009-10-24 22:07:03.918 Got data on select
2009-10-24 22:07:03.919 Processing ready reads
2009-10-24 22:07:03.919 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:03.919 Reacquired ready read lock
2009-10-24 22:07:03.919 ProcessAddRemoveQueues
2009-10-24 22:07:03.919 Construct FD_SET
2009-10-24 22:07:03.919 Waiting on select..
2009-10-24 22:07:03.919 NVP(0): A/V Divergence: 0.85734, Rate: 0.00392615, Warpfactor: 1.00693, warpfactor_avg: 1.00004
2009-10-24 22:07:03.919 TV: DoNVPSeek() -- end
2009-10-24 22:07:03.923 AFD: video timecode 4030891561 4030891561 44787684 44787650
2009-10-24 22:07:03.923 AVSync waitforframe 0 0
2009-10-24 22:07:03.923 AO: Broadcasting free space avail
2009-10-24 22:07:03.923 AO: _AddSamples samples=1536 bytes=6144, used=147457, free=1388543, timecode=44787767 needsupmix 0
2009-10-24 22:07:03.923 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:03.923 MythSocket(57352c0:50): writeBlock(0x140644275303240, 47)
2009-10-24 22:07:03.924 UpdateOSDSeekMessage(Jump Ahead, 2)
2009-10-24 22:07:03.924 MythSocket(57352c0:50): readBlock(0x140644274188056, 8) called
2009-10-24 22:07:03.924 MythSocket(57352c0:50): readBlock(0x140644274253944, 10) called
2009-10-24 22:07:03.924 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6662
2009-10-24 22:07:03.924 Got data on select
2009-10-24 22:07:03.924 Processing ready reads
2009-10-24 22:07:03.924 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:03.924 Reacquired ready read lock
2009-10-24 22:07:03.924 ProcessAddRemoveQueues
2009-10-24 22:07:03.924 Construct FD_SET
2009-10-24 22:07:03.924 Waiting on select..
2009-10-24 22:07:03.924 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:03.924 MythSocket(57352c0:50): writeBlock(0x140644443011288, 47)
2009-10-24 22:07:03.925 MythSocket(57352c0:50): readBlock(0x140644442957832, 8) called
2009-10-24 22:07:03.925 MythSocket(57352c0:50): readBlock(0x140644408416360, 10) called
2009-10-24 22:07:03.925 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6662
2009-10-24 22:07:03.925 Got data on select
2009-10-24 22:07:03.925 Processing ready reads
2009-10-24 22:07:03.925 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:03.925 Reacquired ready read lock
2009-10-24 22:07:03.925 ProcessAddRemoveQueues
2009-10-24 22:07:03.925 Construct FD_SET
2009-10-24 22:07:03.925 Waiting on select..
2009-10-24 22:07:03.948 AVSync show
2009-10-24 22:07:03.954 AO: Broadcasting free space avail
2009-10-24 22:07:03.984 VideoOutputXv: UpdatePauseFrame() UUULUuAUUUUUUUUUUUUUUUUUUUUUUUu
2009-10-24 22:07:03.986 AFD: DoFastForward(6572 (155), do discard frames)
2009-10-24 22:07:03.986 Dec: DoFastForward(6572 (155), do discard frames)
2009-10-24 22:07:03.986 Dec: ConditionallyUpdatePosMap: Not enough info in positionMap,
			we need frame 6572 but highest we have is 6478.
2009-10-24 22:07:03.986 Dec: Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(1)
2009-10-24 22:07:03.986 NVP(0): Filling position map from 6492 to end
2009-10-24 22:07:03.987 MythSocket(57352c0:50): write -> 50 54      QUERY_RECORDER 2[]:[]FILL_POSITION_MAP[]:[]6492[]:[]-1
2009-10-24 22:07:03.987 MythSocket(57352c0:50): writeBlock(0x140644274188424, 62)
2009-10-24 22:07:03.991 MythSocket(57352c0:50): readBlock(0x140644275303464, 8) called
2009-10-24 22:07:03.992 MythSocket(57352c0:50): readBlock(0x140644274161288, 248) called
2009-10-24 22:07:03.992 MythSocket(57352c0:50): read  <- 50 248     6506[]:[]513861904[]:[]6521[]:[]515055328[]:[]6536[]:[]516241044[]:[]6546[]:[]517091744[]:[]6561[]:[]518193048[]:[]6576[]:[]519356580[]:[]6591[]:[]520566736[]:[]6607[]:[]521817312[]:[]6622[]:[]523083304[]:[]6637[]:[]524201716[]:[]6652[]:[]525406984
2009-10-24 22:07:03.992 Got data on select
2009-10-24 22:07:03.992 Processing ready reads
2009-10-24 22:07:03.992 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:03.992 Reacquired ready read lock
2009-10-24 22:07:03.992 ProcessAddRemoveQueues
2009-10-24 22:07:03.992 Construct FD_SET
2009-10-24 22:07:03.992 Waiting on select..
2009-10-24 22:07:03.992 Dec: Position map filled from Encoder to: 6652
2009-10-24 22:07:03.992 Dec: SyncPositionMap watchingrecording total: 564 entries
2009-10-24 22:07:03.992 Dec: SyncPositionMap, new totframes: 6652, new length: 221, posMap size: 564
2009-10-24 22:07:03.992 Dec: FindPosition(6572, search not adjusted) --> 
			[558:6563(519356580),559:6578(520566736)]
2009-10-24 22:07:03.992 MythSocket(5729980:48): write -> 48 75      QUERY_FILETRANSFER 47[]:[]SEEK[]:[]0[]:[]520566736[]:[]0[]:[]0[]:[]14254080
2009-10-24 22:07:03.992 MythSocket(5729980:48): writeBlock(0x140644275303240, 83)
2009-10-24 22:07:03.993 MythSocket(5729980:48): readBlock(0x140644274188056, 8) called
2009-10-24 22:07:03.993 MythSocket(5729980:48): readBlock(0x140644274253944, 15) called
2009-10-24 22:07:03.993 MythSocket(5729980:48): read  <- 48 15      0[]:[]520566736
2009-10-24 22:07:03.993 Got data on select
2009-10-24 22:07:03.993 Processing ready reads
2009-10-24 22:07:03.993 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:03.993 Reacquired ready read lock
2009-10-24 22:07:03.993 ProcessAddRemoveQueues
2009-10-24 22:07:03.994 AO: Broadcasting free space avail
2009-10-24 22:07:03.993 Construct FD_SET
2009-10-24 22:07:03.994 Waiting on select..
2009-10-24 22:07:03.994 AFD: SeekReset(6578, 0, do flush, do discard)
2009-10-24 22:07:03.994 AFD: SeekReset() flushing
2009-10-24 22:07:03.994 VideoOutputXv: DiscardFrames(1)
2009-10-24 22:07:03.994 VideoBuffers::DiscardFrames(1): UUUAUUAUUUUUUUUUUUUUUUUUUUUUUUU
2009-10-24 22:07:03.994 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 22:07:03.994 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-10-24 22:07:03.994 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 22:07:03.994 NVP(0): ClearAfterSeek(0)
2009-10-24 22:07:03.994 AO: Pause 1
2009-10-24 22:07:03.995 MythSocket(5729980:48): write -> 48 49      QUERY_FILETRANSFER 47[]:[]REQUEST_BLOCK[]:[]32768
2009-10-24 22:07:03.995 MythSocket(5729980:48): writeBlock(0x112571256, 57)
2009-10-24 22:07:04.015 NVP(0): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.138 /dev/scd0 No disc
2009-10-24 22:07:04.165 NVP(0): Waiting for prebuffer..  1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.315 NVP(0): Waiting for prebuffer..  2 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.426 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:04.426 MythSocket(57352c0:50): writeBlock(0x140644443019512, 47)
2009-10-24 22:07:04.427 MythSocket(57352c0:50): readBlock(0x140644442958248, 8) called
2009-10-24 22:07:04.427 MythSocket(57352c0:50): readBlock(0x140644408438040, 10) called
2009-10-24 22:07:04.427 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6677
2009-10-24 22:07:04.427 Got data on select
2009-10-24 22:07:04.427 Processing ready reads
2009-10-24 22:07:04.427 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:04.427 Reacquired ready read lock
2009-10-24 22:07:04.427 ProcessAddRemoveQueues
2009-10-24 22:07:04.427 Construct FD_SET
2009-10-24 22:07:04.427 Waiting on select..
2009-10-24 22:07:04.466 NVP(0): Waiting for prebuffer..  3 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.615 NVP(0): Waiting for prebuffer..  4 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.642 /dev/scd0 No disc
2009-10-24 22:07:04.748 Setting Master volume to 54
2009-10-24 22:07:04.748 Volume range is 0 to 31, mult=3.22581
2009-10-24 22:07:04.748 channel 0 vol set to 17
2009-10-24 22:07:04.748 Volume range is 0 to 31, mult=3.22581
2009-10-24 22:07:04.749 Mixer unable to find control Master 1
2009-10-24 22:07:04.749 ALSA: channel 0 playback switch set to 1
2009-10-24 22:07:04.749 Setting Master volume to 54
2009-10-24 22:07:04.749 mixer unable to find control Master 1
2009-10-24 22:07:04.765 NVP(0): Waiting for prebuffer..  5 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.915 NVP(0): Waiting for prebuffer..  6 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:04.929 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:04.929 MythSocket(57352c0:50): writeBlock(0x140644443019512, 47)
2009-10-24 22:07:04.930 MythSocket(57352c0:50): readBlock(0x140644442950792, 8) called
2009-10-24 22:07:04.930 MythSocket(57352c0:50): readBlock(0x140644408444168, 10) called
2009-10-24 22:07:04.930 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6692
2009-10-24 22:07:04.930 Got data on select
2009-10-24 22:07:04.930 Processing ready reads
2009-10-24 22:07:04.930 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:04.930 Reacquired ready read lock
2009-10-24 22:07:04.930 ProcessAddRemoveQueues
2009-10-24 22:07:04.930 Construct FD_SET
2009-10-24 22:07:04.930 Waiting on select..
2009-10-24 22:07:05.015 RingBuf(myth://10.0.0.210:6543/3411_20091024220300.mpg): Taking too long to be allowed to read..
2009-10-24 22:07:05.065 NVP(0): Waiting for prebuffer..  7 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:05.146 /dev/scd0 No disc
2009-10-24 22:07:05.214 NVP(0): Waiting for prebuffer..  8 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:05.364 NVP(0): Waiting for prebuffer..  9 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:05.431 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:05.431 MythSocket(57352c0:50): writeBlock(0x140644443019512, 47)
2009-10-24 22:07:05.432 MythSocket(57352c0:50): readBlock(0x140644442958248, 8) called
2009-10-24 22:07:05.432 MythSocket(57352c0:50): readBlock(0x140644409284280, 10) called
2009-10-24 22:07:05.432 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6707
2009-10-24 22:07:05.432 Got data on select
2009-10-24 22:07:05.432 Processing ready reads
2009-10-24 22:07:05.432 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:05.432 Reacquired ready read lock
2009-10-24 22:07:05.432 ProcessAddRemoveQueues
2009-10-24 22:07:05.432 Construct FD_SET
2009-10-24 22:07:05.432 Waiting on select..
2009-10-24 22:07:05.498 NVP(0): Prebuffer wait timed out 10 times.
2009-10-24 22:07:05.514 NVP(0): Waiting for prebuffer.. 10 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:05.652 /dev/scd0 No disc
2009-10-24 22:07:05.664 NVP(0): Waiting for prebuffer.. 11 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:05.814 NVP(0): Waiting for prebuffer.. 12 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:05.933 MythSocket(57352c0:50): write -> 50 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
2009-10-24 22:07:05.933 MythSocket(57352c0:50): writeBlock(0x140644442957688, 47)
2009-10-24 22:07:05.934 MythSocket(57352c0:50): readBlock(0x140644442958248, 8) called
2009-10-24 22:07:05.934 MythSocket(57352c0:50): readBlock(0x140644408438728, 10) called
2009-10-24 22:07:05.934 MythSocket(57352c0:50): read  <- 50 10      0[]:[]6722
2009-10-24 22:07:05.934 Got data on select
2009-10-24 22:07:05.934 Processing ready reads
2009-10-24 22:07:05.934 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:05.934 Reacquired ready read lock
2009-10-24 22:07:05.934 ProcessAddRemoveQueues
2009-10-24 22:07:05.934 Construct FD_SET
2009-10-24 22:07:05.934 Waiting on select..
2009-10-24 22:07:05.964 NVP(0): Waiting for prebuffer.. 13 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:06.015 RingBuf(myth://10.0.0.210:6543/3411_20091024220300.mpg): Taking too long to be allowed to read..
2009-10-24 22:07:06.114 NVP(0): Waiting for prebuffer.. 14 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:06.156 /dev/scd0 No disc
2009-10-24 22:07:06.264 NVP(0): Waiting for prebuffer.. 15 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:06.395 MythSocket(5729980:48): readBlock(0x133222312, 8) called
2009-10-24 22:07:06.395 MythSocket(5729980:48): readBlock(0x112545784, 1) called
2009-10-24 22:07:06.395 MythSocket(5729980:48): read  <- 48 1       0
2009-10-24 22:07:06.395 Got data on select
2009-10-24 22:07:06.395 Processing ready reads
2009-10-24 22:07:06.395 MythSocketThread: Total read time: 0ms, on sockets
2009-10-24 22:07:06.395 Reacquired ready read lock
2009-10-24 22:07:06.395 ProcessAddRemoveQueues
2009-10-24 22:07:06.395 Construct FD_SET
2009-10-24 22:07:06.395 Waiting on select..
2009-10-24 22:07:06.395 Read(): reqd=32768, rcvd=0, rept=0, error=0
2009-10-24 22:07:06.395 NVP(0): Exited decoder loop.
2009-10-24 22:07:06.414 ~OpenGLVideoSync() -- closing opengl vsync
2009-10-24 22:07:06.414 GLCtx: Deleting OpenGL Resources
2009-10-24 22:07:06.414 GLCtx: Unmapping gl window
2009-10-24 22:07:06.414 GLCtx: Destroying gl window
2009-10-24 22:07:06.414 GLCtx: Destroying glx context
2009-10-24 22:07:06.416 GLCtx: Closing display
2009-10-24 22:07:06.417 VideoOutputXv: dtor
2009-10-24 22:07:06.417 VideoOutputXv: DiscardFrames(1)
2009-10-24 22:07:06.417 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:06.417 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 22:07:06.417 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-10-24 22:07:06.417 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 22:07:06.417 VideoOutputXv: DiscardFrames(1)
2009-10-24 22:07:06.417 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-10-24 22:07:06.417 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 22:07:06.417 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-10-24 22:07:06.417 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 22:07:06.422 VideoOutputXv: Closing XVideo port 224
2009-10-24 22:07:06.455 TV: HandleStateChange(0) -- begin
2009-10-24 22:07:06.456 TV: Attempting to change from Watching WatchingRecording to None
2009-10-24 22:07:06.456 TV: StopStuff() for player ctx 0 -- begin
2009-10-24 22:07:06.456 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2009-10-24 22:07:06.456 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2009-10-24 22:07:06.456 TV: StopStuff(): stopping ring buffer
2009-10-24 22:07:06.497 TV: StopStuff(): stopping player
2009-10-24 22:07:06.497 TV: StopStuff() -- end
2009-10-24 22:07:06.497 TV: Changing from Watching WatchingRecording to None
2