2012-04-03 15:40:40.959088 C [1116/1116] thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) - mythbackend version: master [v0.25-rc-122-gf81f712] www.mythtv.org
2012-04-03 15:40:40.959164 N [1116/1116] thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) - Enabled verbose msgs:  general
2012-04-03 15:40:40.959416 N [1116/1116] thread_unknown logging.cpp:1176 (logStart) - Setting Log Level to LOG_INFO
2012-04-03 15:40:40.959544 I [1116/1116] thread_unknown logging.cpp:229 (FileLogger) - Added logging to the console
2012-04-03 15:40:40.967051 I [1116/1116] thread_unknown logging.cpp:238 (FileLogger) - Added logging to /var/log/mythbackend/mythbackend.20120403154040.1116.log
2012-04-03 15:40:40.967060 I [1116/1116] thread_unknown logging.cpp:425 (DatabaseLogger) - Added database logging to table logging
2012-04-03 15:40:40.967233 N [1116/1116] thread_unknown logging.cpp:1215 (logStart) - Setting up SIGHUP handler
2012-04-03 15:40:40.967429 N [1116/1116] thread_unknown mythdirs.cpp:51 (InitializeMythDirs) - Using runtime prefix = /usr
2012-04-03 15:40:40.967436 N [1116/1116] thread_unknown mythdirs.cpp:57 (InitializeMythDirs) - Read conf dir = /etc/mythtv
2012-04-03 15:40:40.967570 N [1116/1116] thread_unknown mythdirs.cpp:64 (InitializeMythDirs) - Using configuration directory = /etc/mythtv
2012-04-03 15:40:40.981281 I [1116/1116] CoreContext mythcorecontext.cpp:226 (Init) - Assumed character encoding: en_US.UTF-8
2012-04-03 15:40:40.981977 E [1116/1116] CoreContext configuration.cpp:64 (Load) - Error parsing: /etc/mythtv/config.xml at line: 1  column: 1
2012-04-03 15:40:40.981993 E [1116/1116] CoreContext configuration.cpp:66 (Load) - Error Msg: unexpected end of file
2012-04-03 15:40:40.982400 N [1116/1116] CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) - Empty LocalHostName.
2012-04-03 15:40:40.982416 I [1116/1116] CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) - Using localhost value of minako
2012-04-03 15:40:40.982490 E [1116/1116] CoreContext configuration.cpp:64 (Load) - Error parsing: /etc/mythtv/config.xml at line: 1  column: 1
2012-04-03 15:40:40.982495 E [1116/1116] CoreContext configuration.cpp:66 (Load) - Error Msg: unexpected end of file
2012-04-03 15:40:40.998613 I [1116/1116] CoreContext mythcorecontext.cpp:1276 (SaveLocaleDefaults) - Current locale EN_US
2012-04-03 15:40:40.998708 N [1116/1116] CoreContext mythlocale.cpp:110 (LoadDefaultsFromXML) - Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2012-04-03 15:40:41.027479 I [1116/1116] CoreContext schemawizard.cpp:117 (Compare) - Current MythTV Schema Version (DBSchemaVer): 1299
2012-04-03 15:40:41.061965 I [1116/1116] CoreContext mythtranslation.cpp:66 (load) - Loading en_us translation for module mythfrontend
2012-04-03 15:40:41.062483 N [1116/1116] CoreContext main_helpers.cpp:556 (run_backend) - MythBackend: Starting up as the master server.
2012-04-03 15:40:48.100336 I [1116/1116] CoreContext programinfo.cpp:2052 (CheckProgramIDAuthorities) - Found 9 distinct programid authorities
2012-04-03 15:40:48.100651 I [1116/1166] Scheduler mythdbcon.cpp:422 (getStaticCon) - New static DB connectionSchedCon
2012-04-03 15:40:48.225205 I [1116/1116] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 127.0.0.1:6544
2012-04-03 15:40:48.225282 I [1116/1116] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 192.168.0.31:6544
2012-04-03 15:40:48.401758 I [1116/1116] CoreContext serverpool.cpp:306 (listen) - Listening on TCP [0:0:0:0:0:0:0:1]:6544
2012-04-03 15:40:48.955159 I [1116/1116] CoreContext main_helpers.cpp:626 (run_backend) - Main::Registering HttpStatus Extension
2012-04-03 15:40:48.956924 I [1116/1116] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 127.0.0.1:6543
2012-04-03 15:40:48.956979 I [1116/1116] CoreContext serverpool.cpp:306 (listen) - Listening on TCP 192.168.0.31:6543
2012-04-03 15:40:48.959034 I [1116/1116] CoreContext serverpool.cpp:306 (listen) - Listening on TCP [0:0:0:0:0:0:0:1]:6543
2012-04-03 15:40:49.021454 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 15:40:51.203343 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 15:40:52.546454 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 15:40:52.575841 I [1116/1166] Scheduler scheduler.cpp:2136 (HandleRunSchedulerStartup) - Scheduler: Seem to be woken up by USER
2012-04-03 15:40:58.103288 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 15:42:08.021724 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 15:43:28.215656 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Monitor
2012-04-03 15:43:28.215666 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 15:43:28.221662 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Monitor
2012-04-03 15:43:28.221668 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 1)
2012-04-03 15:44:38.194640 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:44:38.194648 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 15:44:38.202082 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 15:44:38.202086 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: mum-myth as a remote file transfer
2012-04-03 15:46:01.107921 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 15:46:27.428889 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 15:46:28.781965 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.96 match + 0.40 place
2012-04-03 15:47:46.831698 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:47:46.831706 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 15:47:46.839057 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 15:47:46.839061 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: mum-myth as a remote file transfer
2012-04-03 15:49:22.414072 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:49:22.414081 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 15:49:22.422253 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 15:49:22.422257 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: mum-myth as a remote file transfer
2012-04-03 15:50:51.299618 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Monitor
2012-04-03 15:50:51.299626 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 15:50:51.306364 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Monitor
2012-04-03 15:50:51.306371 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 1)
2012-04-03 15:51:05.109493 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 15:51:05.109858 I [1116/1167] HouseKeeping housekeeper.cpp:233 (RunHouseKeeping) - Running LogClean
2012-04-03 15:51:32.360635 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:51:32.360643 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 15:51:32.368056 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 15:51:32.368061 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: mum-myth as a remote file transfer
2012-04-03 15:51:43.237240 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:51:43.237248 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 15:51:43.335233 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from None to WatchingLiveTV
2012-04-03 15:51:43.336901 I [1116/1155] TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) - TVRec(7): HW Tuner: 7->7
2012-04-03 15:51:44.401811 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2012-04-03 15:51:44.680943 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 15:51:46.509336 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2012-04-03 15:51:46.531826 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.8 = 0.98 match + 0.86 place
2012-04-03 15:51:47.021765 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:51:47.021773 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 15:51:47.037065 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 15:51:47.037071 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: michiru as a remote file transfer
2012-04-03 15:51:48.241342 I [1116/1220] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(7:/dev/dvb/adapter1/frontend0): GetKeyframePositions(13,9223372036854775807,#1) out of 3
2012-04-03 15:51:48.577745 I [1116/1220] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(7:/dev/dvb/adapter1/frontend0): GetKeyframePositions(13,9223372036854775807,#2) out of 4
2012-04-03 15:51:56.111461 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from WatchingLiveTV to None
2012-04-03 15:51:56.118451 E [1116/1232] DVBRead dvbstreamhandler.cpp:214 (RunTS) - DVBSH(/dev/dvb/adapter1/frontend0): Device EOF detected
2012-04-03 15:51:56.204424 I [1116/1155] TVRecEvent tv_rec.cpp:812 (FinishedRecording) - TVRec(7): FinishedRecording(1005_2012-04-03T15:51:46) damaged recq:<RecordingQuality overall_score="0" key="1005_2012-04-03T15:51:46" countinuity_error_count="0" packet_count="36109">
    <Gap start="2012-04-03T15:29:58" end="2012-04-03T15:51:46" duration="1308" />
    <Gap start="2012-04-03T15:51:54" end="2012-04-03T16:00:41" duration="527" />
</RecordingQuality>

2012-04-03 15:54:07.512276 N [1116/1168] Expire autoexpire.cpp:640 (SendDeleteMessages) - Expiring 0 MB for 1005 at 2012-04-03T15:51:44 => "Good Chef Bad Chef"
2012-04-03 15:54:07.512336 N [1116/1168] Expire autoexpire.cpp:640 (SendDeleteMessages) - Expiring 6 MB for 1005 at 2012-04-03T15:51:46 => "Good Chef Bad Chef"
2012-04-03 15:56:14.695190 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 15:57:03.037143 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 15:57:04.387224 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.39 place
2012-04-03 15:58:07.510627 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 15:59:54.910330 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 15:59:54.910338 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 15:59:54.920550 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 15:59:54.920555 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: mum-myth as a remote file transfer
2012-04-03 16:01:14.699427 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:02:10.337573 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 16:02:10.337581 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: mum-myth as a client (events: 0)
2012-04-03 16:02:10.346324 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 16:02:10.346329 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: mum-myth as a remote file transfer
2012-04-03 16:02:21.419416 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:02:22.783717 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.98 match + 0.38 place
2012-04-03 16:06:21.700855 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:07:39.013147 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:07:40.365382 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.96 match + 0.39 place
2012-04-03 16:11:27.702340 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:12:58.603739 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:12:59.955546 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.96 match + 0.39 place
2012-04-03 16:13:07.514496 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 16:16:27.703782 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:18:14.126246 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:18:15.475468 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.39 place
2012-04-03 16:21:27.705207 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:23:32.476489 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:23:33.816138 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 16:26:30.706626 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:27:48.124585 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:27:49.462023 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 16:28:07.516775 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 16:31:02.469527 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:31:03.815481 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 16:31:33.708106 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:34:07.590129 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:34:08.930727 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 16:36:36.709508 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:39:27.600979 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:39:28.949144 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.97 match + 0.38 place
2012-04-03 16:41:36.710929 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:43:07.519028 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 16:44:44.357935 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:44:45.709396 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.97 match + 0.38 place
2012-04-03 16:46:38.712330 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:50:02.339833 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:50:03.678176 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 16:50:50.572785 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 16:50:50.572793 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 16:50:50.592359 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 16:50:50.592363 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: michiru as a remote file transfer
2012-04-03 16:51:44.713737 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:54:13.584544 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 16:54:14.925523 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 16:56:44.715145 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 16:58:07.521388 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 17:01:10.376686 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:01:11.733026 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.97 match + 0.39 place
2012-04-03 17:01:51.716572 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:04:29.654048 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:04:30.990710 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 17:06:57.717980 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:11:14.287993 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:11:15.642617 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.97 match + 0.39 place
2012-04-03 17:11:59.719421 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:13:07.522991 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 17:16:32.636111 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:16:33.990754 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.97 match + 0.39 place
2012-04-03 17:17:02.720789 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:22:03.722185 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:27:05.723628 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:28:07.523658 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 17:31:23.895080 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:31:25.225590 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.37 place
2012-04-03 17:32:07.725023 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:34:24.601369 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:34:25.932818 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 17:37:13.726360 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:37:44.330137 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:37:45.672972 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 17:42:14.727765 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:43:02.304881 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:43:03.640905 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.95 match + 0.38 place
2012-04-03 17:43:07.524769 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 17:46:31.543136 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:46:32.903420 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.98 match + 0.38 place
2012-04-03 17:47:16.729230 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:50:50.506461 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 17:50:50.506468 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 17:50:50.520725 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 17:50:50.520730 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: michiru as a remote file transfer
2012-04-03 17:52:20.730666 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:53:45.510936 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:53:46.863143 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.96 match + 0.40 place
2012-04-03 17:57:27.732094 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 17:58:07.525075 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 17:59:05.732170 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 17:59:07.085197 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.95 match + 0.40 place
2012-04-03 18:02:28.733764 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:04:13.719394 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:04:15.070363 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.96 match + 0.39 place
2012-04-03 18:07:35.735235 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:09:49.434576 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:09:50.800570 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.96 match + 0.41 place
2012-04-03 18:12:41.736696 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:13:07.526165 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 15.0 GB w/freq: 15 min
2012-04-03 18:13:47.481125 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:13:48.823230 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.39 place
2012-04-03 18:17:43.738161 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:20:07.975716 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:20:09.324278 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.39 place
2012-04-03 18:22:49.739599 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:23:03.181214 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 18:23:03.181222 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 18:23:03.262750 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from None to WatchingLiveTV
2012-04-03 18:23:03.262903 I [1116/1155] TVRecEvent mythdbcon.cpp:395 (PurgeIdleConnections) - New DB connection, total: 14
2012-04-03 18:23:03.264676 I [1116/1155] TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) - TVRec(7): HW Tuner: 7->7
2012-04-03 18:23:03.813822 I [1116/1116] CoreContext mythdbcon.cpp:395 (PurgeIdleConnections) - New DB connection, total: 14
2012-04-03 18:23:04.258674 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2012-04-03 18:23:05.646313 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2012-04-03 18:23:05.947312 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 18:23:05.947320 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 18:23:05.955850 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 18:23:05.955855 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: michiru as a remote file transfer
2012-04-03 18:23:07.419529 I [1116/1398] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(7:/dev/dvb/adapter1/frontend0): GetKeyframePositions(13,9223372036854775807,#1) out of 3
2012-04-03 18:23:07.888748 I [1116/1398] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(7:/dev/dvb/adapter1/frontend0): GetKeyframePositions(13,9223372036854775807,#2) out of 4
2012-04-03 18:23:18.252330 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 192464 < 196608
2012-04-03 18:23:51.072513 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130332 < 196608
2012-04-03 18:23:59.566342 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 157252 < 196608
2012-04-03 18:24:05.901464 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 181596 < 196608
2012-04-03 18:24:06.392237 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 139376 < 196608
2012-04-03 18:24:06.648069 N [1116/1168] Expire autoexpire.cpp:640 (SendDeleteMessages) - Expiring 0 MB for 1005 at 2012-04-03T18:23:04 => "The Project"
2012-04-03 18:24:12.186721 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 154880 < 196608
2012-04-03 18:24:18.957767 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130424 < 196608
2012-04-03 18:24:21.662092 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 31748 < 196608
2012-04-03 18:24:25.117817 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 185072 < 196608
2012-04-03 18:24:25.608574 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 142288 < 196608
2012-04-03 18:24:39.727007 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 186968 < 196608
2012-04-03 18:24:46.202153 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 172960 < 196608
2012-04-03 18:24:52.041568 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 191848 < 196608
2012-04-03 18:25:04.426523 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 127736 < 196608
2012-04-03 18:25:27.214890 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:25:28.559766 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.98 match + 0.36 place
2012-04-03 18:25:50.040916 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 121740 < 196608
2012-04-03 18:26:09.272016 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 167440 < 196608
2012-04-03 18:26:16.188625 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 193596 < 196608
2012-04-03 18:26:20.260002 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 177048 < 196608
2012-04-03 18:26:24.697291 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 175432 < 196608
2012-04-03 18:26:46.608227 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 145016 < 196608
2012-04-03 18:27:01.096486 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 126380 < 196608
2012-04-03 18:27:09.344773 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 157772 < 196608
2012-04-03 18:27:11.318012 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 178732 < 196608
2012-04-03 18:27:15.895604 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 159784 < 196608
2012-04-03 18:27:19.967236 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 126732 < 196608
2012-04-03 18:27:25.320824 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 182576 < 196608
2012-04-03 18:27:44.171306 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 147396 < 196608
2012-04-03 18:27:51.498106 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 124148 < 196608
2012-04-03 18:27:52.741051 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:27:56.230628 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 158856 < 196608
2012-04-03 18:28:07.738947 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 138276 < 196608
2012-04-03 18:28:20.369217 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 169556 < 196608
2012-04-03 18:28:21.791575 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 176900 < 196608
2012-04-03 18:28:24.936599 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 152864 < 196608
2012-04-03 18:28:29.102998 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161548 < 196608
2012-04-03 18:28:34.331543 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162684 < 196608
2012-04-03 18:29:06.647936 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2012-04-03 18:29:08.391831 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 193172 < 196608
2012-04-03 18:29:14.066168 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 148816 < 196608
2012-04-03 18:29:17.877263 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 122492 < 196608
2012-04-03 18:29:30.828475 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 138772 < 196608
2012-04-03 18:29:41.682714 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:29:43.015981 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.38 place
2012-04-03 18:29:43.725422 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 144900 < 196608
2012-04-03 18:29:45.448162 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 190824 < 196608
2012-04-03 18:29:46.494878 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 156016 < 196608
2012-04-03 18:29:52.775001 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 186752 < 196608
2012-04-03 18:30:11.690604 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 176012 < 196608
2012-04-03 18:30:22.898686 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 172500 < 196608
2012-04-03 18:30:34.587269 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 94244 < 196608
2012-04-03 18:30:54.795033 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 182064 < 196608
2012-04-03 18:30:58.300709 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 195480 < 196608
2012-04-03 18:31:01.821469 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 149188 < 196608
2012-04-03 18:31:09.278561 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 129588 < 196608
2012-04-03 18:31:10.330286 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161256 < 196608
2012-04-03 18:31:12.293438 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 177892 < 196608
2012-04-03 18:31:17.531979 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 169064 < 196608
2012-04-03 18:31:34.223881 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 182820 < 196608
2012-04-03 18:32:00.596388 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 189328 < 196608
2012-04-03 18:32:27.679822 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 140684 < 196608
2012-04-03 18:32:30.819883 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 193388 < 196608
2012-04-03 18:32:31.876685 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 167604 < 196608
2012-04-03 18:32:33.168779 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 182580 < 196608
2012-04-03 18:32:35.933172 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 192944 < 196608
2012-04-03 18:32:53.742472 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:33:02.551407 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 169976 < 196608
2012-04-03 18:33:18.819865 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:33:19.554319 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 191332 < 196608
2012-04-03 18:33:20.259136 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.95 match + 0.48 place
2012-04-03 18:33:33.492250 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 191940 < 196608
2012-04-03 18:33:34.654046 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 115228 < 163840
2012-04-03 18:33:40.924259 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162752 < 163840
2012-04-03 18:34:00.881614 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 143580 < 163840
2012-04-03 18:34:04.081691 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 125748 < 163840
2012-04-03 18:34:16.356839 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 138300 < 163840
2012-04-03 18:34:18.635602 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 96904 < 163840
2012-04-03 18:34:19.371753 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 127648 < 163840
2012-04-03 18:34:30.404865 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 81252 < 163840
2012-04-03 18:34:35.448002 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 82424 < 163840
2012-04-03 18:35:29.912035 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162608 < 163840
2012-04-03 18:35:49.249737 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:35:50.578783 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.96 match + 0.37 place
2012-04-03 18:35:57.824056 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 71464 < 163840
2012-04-03 18:35:59.260162 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 138592 < 163840
2012-04-03 18:36:10.157523 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 149816 < 163840
2012-04-03 18:36:27.710858 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 151416 < 163840
2012-04-03 18:36:32.818957 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162684 < 163840
2012-04-03 18:36:36.970941 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 163284 < 163840
2012-04-03 18:36:51.945075 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 86692 < 163840
2012-04-03 18:37:30.932089 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 16644 < 163840
2012-04-03 18:37:35.184080 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 82292 < 163840
2012-04-03 18:37:46.295068 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 118804 < 163840
2012-04-03 18:37:46.733525 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 140428 < 163840
2012-04-03 18:37:56.213637 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 127012 < 163840
2012-04-03 18:37:57.743855 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:38:04.341522 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 124300 < 163840
2012-04-03 18:38:50.066097 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 67508 < 163840
2012-04-03 18:39:07.379289 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 108116 < 163840
2012-04-03 18:39:14.160374 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 136412 < 163840
2012-04-03 18:39:14.651136 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162736 < 163840
2012-04-03 18:39:18.330070 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 103924 < 163840
2012-04-03 18:39:37.460046 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 65720 < 163840
2012-04-03 18:39:49.122859 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 159764 < 163840
2012-04-03 18:40:07.988727 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 123212 < 163840
2012-04-03 18:40:26.664032 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 69024 < 163840
2012-04-03 18:40:38.563289 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 141356 < 163840
2012-04-03 18:40:49.635943 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 127108 < 163840
2012-04-03 18:40:52.405472 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 141420 < 163840
2012-04-03 18:40:57.528824 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161580 < 163840
2012-04-03 18:40:58.124055 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 72944 < 163840
2012-04-03 18:41:02.142095 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 104224 < 163840
2012-04-03 18:41:15.549042 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 104284 < 163840
2012-04-03 18:41:19.023292 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 152348 < 163840
2012-04-03 18:41:21.207972 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:41:22.550511 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.3 = 0.98 match + 0.36 place
2012-04-03 18:41:30.472067 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 48616 < 163840
2012-04-03 18:41:41.896233 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130720 < 163840
2012-04-03 18:41:51.386654 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130652 < 163840
2012-04-03 18:41:55.568345 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 154376 < 163840
2012-04-03 18:42:03.508052 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 32472 < 163840
2012-04-03 18:42:20.203242 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 81252 < 163840
2012-04-03 18:42:26.107605 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162744 < 163840
2012-04-03 18:42:31.821740 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 147472 < 163840
2012-04-03 18:42:36.008396 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 135984 < 163840
2012-04-03 18:42:41.924054 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 72116 < 163840
2012-04-03 18:43:02.745311 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:43:06.224066 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 70616 < 163840
2012-04-03 18:43:06.650604 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 14 min
2012-04-03 18:43:21.127652 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 153352 < 163840
2012-04-03 18:43:27.362876 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 148424 < 163840
2012-04-03 18:44:03.201229 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162196 < 163840
2012-04-03 18:44:10.593265 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130620 < 163840
2012-04-03 18:44:12.859050 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 82136 < 163840
2012-04-03 18:44:45.404341 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 85164 < 163840
2012-04-03 18:45:21.362655 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 154396 < 163840
2012-04-03 18:45:29.811321 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 103240 < 163840
2012-04-03 18:45:33.708059 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 26984 < 163840
2012-04-03 18:45:50.174606 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 122480 < 163840
2012-04-03 18:45:53.449995 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 158604 < 163840
2012-04-03 18:45:56.725340 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 155004 < 163840
2012-04-03 18:46:02.464631 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 125632 < 163840
2012-04-03 18:46:11.955030 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 136844 < 163840
2012-04-03 18:46:47.813076 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 146856 < 163840
2012-04-03 18:46:58.385671 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 83536 < 163840
2012-04-03 18:47:19.259806 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 139064 < 163840
2012-04-03 18:47:32.876715 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161780 < 163840
2012-04-03 18:47:51.219067 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 17312 < 163840
2012-04-03 18:47:52.223063 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 81936 < 163840
2012-04-03 18:48:09.746732 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:48:09.801665 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 106284 < 163840
2012-04-03 18:48:21.641080 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 119020 < 163840
2012-04-03 18:48:33.405176 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 123484 < 163840
2012-04-03 18:48:39.680298 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 140308 < 163840
2012-04-03 18:48:47.077274 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 132476 < 163840
2012-04-03 18:49:16.670381 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 124856 < 163840
2012-04-03 18:49:23.665873 I [1116/1143] TVRecEvent tv_rec.cpp:1518 (HandlePendingRecordings) - TVRec(1): ASK_RECORDING 1 6 0 0
2012-04-03 18:49:24.020437 I [1116/1159] TVRecEvent tv_rec.cpp:1518 (HandlePendingRecordings) - TVRec(9): ASK_RECORDING 9 6 0 0
2012-04-03 18:49:24.081420 I [1116/1145] TVRecEvent tv_rec.cpp:1518 (HandlePendingRecordings) - TVRec(2): ASK_RECORDING 2 6 0 0
2012-04-03 18:49:25.162080 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 56968 < 163840
2012-04-03 18:49:33.197855 I [1116/1143] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(1): Changing from None to RecordingOnly
2012-04-03 18:49:33.197910 I [1116/1143] TVRecEvent mythdbcon.cpp:395 (PurgeIdleConnections) - New DB connection, total: 16
2012-04-03 18:49:33.198549 I [1116/1143] TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) - TVRec(1): HW Tuner: 1->1
2012-04-03 18:49:33.932463 N [1116/1166] Scheduler autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 7 min
2012-04-03 18:49:33.932489 I [1116/1166] Scheduler scheduler.cpp:2460 (HandleRecordingStatusChange) - Tuning recording: "The Big Bang Theory": channel 1008 on cardid 1, sourceid 1
2012-04-03 18:49:34.227457 I [1116/1116] CoreContext scheduler.cpp:635 (UpdateRecStatus) - Updating status for "The Big Bang Theory" on cardid 1 (Tuning => Recording)
2012-04-03 18:49:34.279518 I [1116/1143] TVRecEvent tv_rec.cpp:3950 (TuningNewRecorder) - TVRec(1): rec->GetPathname(): '/var/video/video2/video2/1008_20120403185000.mpg'
2012-04-03 18:49:36.753615 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 120180 < 163840
2012-04-03 18:49:41.010793 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161708 < 163840
2012-04-03 18:49:59.166904 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 117348 < 163840
2012-04-03 18:49:59.657677 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 135588 < 163840
2012-04-03 18:50:13.221080 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 62612 < 163840
2012-04-03 18:50:27.464657 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 145436 < 163840
2012-04-03 18:50:35.523232 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 18:50:36.880031 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.97 match + 0.38 place
2012-04-03 18:50:37.626941 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 121780 < 163840
2012-04-03 18:51:19.202130 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 119904 < 163840
2012-04-03 18:51:29.865097 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 151900 < 163840
2012-04-03 18:51:43.743588 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 140872 < 163840
2012-04-03 18:52:00.869087 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 110452 < 163840
2012-04-03 18:52:07.283099 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130436 < 163840
2012-04-03 18:52:51.981348 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 157576 < 163840
2012-04-03 18:52:58.827942 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 98172 < 163840
2012-04-03 18:53:10.748144 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:53:15.541011 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 154284 < 163840
2012-04-03 18:53:16.884066 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 96956 < 163840
2012-04-03 18:53:28.397989 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 70680 < 163840
2012-04-03 18:53:37.683357 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130300 < 163840
2012-04-03 18:53:39.536501 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 101740 < 163840
2012-04-03 18:53:47.670255 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 130612 < 163840
2012-04-03 18:54:05.730792 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161544 < 163840
2012-04-03 18:54:06.787778 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 96036 < 163840
2012-04-03 18:54:19.564630 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 141304 < 163840
2012-04-03 18:54:24.182324 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 144296 < 163840
2012-04-03 18:54:32.430964 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 118800 < 163840
2012-04-03 18:54:33.417620 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 124392 < 163840
2012-04-03 18:54:35.696450 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 118396 < 163840
2012-04-03 18:54:36.187251 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 144156 < 163840
2012-04-03 18:54:39.883460 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 138548 < 163840
2012-04-03 18:54:46.654698 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 156880 < 163840
2012-04-03 18:55:00.212621 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 127332 < 163840
2012-04-03 18:55:02.997363 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 144652 < 163840
2012-04-03 18:55:03.428077 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 104988 < 163840
2012-04-03 18:55:38.607755 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 129940 < 163840
2012-04-03 18:55:43.921864 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 116864 < 163840
2012-04-03 18:55:49.716507 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 149532 < 163840
2012-04-03 18:56:22.378876 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 135452 < 163840
2012-04-03 18:56:40.830020 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 143640 < 163840
2012-04-03 18:56:57.183064 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 44876 < 163840
2012-04-03 18:57:01.870439 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 146812 < 163840
2012-04-03 18:57:12.218935 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 129456 < 163840
2012-04-03 18:57:12.709652 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 155404 < 163840
2012-04-03 18:57:21.834588 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Monitor
2012-04-03 18:57:21.834596 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 0)
2012-04-03 18:57:21.836370 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Monitor
2012-04-03 18:57:21.836374 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 1)
2012-04-03 18:57:26.392602 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161764 < 163840
2012-04-03 18:57:36.013714 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 75500 < 163840
2012-04-03 18:57:36.128528 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 18:57:36.128536 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 0)
2012-04-03 18:57:36.132553 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 18:57:36.132558 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: naru as a remote file transfer
2012-04-03 18:57:40.275974 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 162016 < 163840
2012-04-03 18:57:46.042031 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 18:57:46.042040 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 0)
2012-04-03 18:57:46.090262 I [1116/1157] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(8): Changing from None to WatchingLiveTV
2012-04-03 18:57:46.099384 I [1116/1157] TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) - TVRec(8): HW Tuner: 8->8
2012-04-03 18:57:46.647703 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 4 min
2012-04-03 18:57:47.732545 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 4 min
2012-04-03 18:57:48.696149 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 18:57:48.696158 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 0)
2012-04-03 18:57:48.698155 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 18:57:48.698160 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: naru as a remote file transfer
2012-04-03 18:57:49.921454 I [1116/1462] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(8:/dev/dvb/adapter1/frontend0): GetKeyframePositions(1,9223372036854775807,#6) out of 7
2012-04-03 18:57:50.098364 I [1116] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(8:/dev/dvb/adapter1/frontend0): GetKeyframePositions(1,9223372036854775807,#7) out of 8
2012-04-03 18:58:05.737106 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 4 min
2012-04-03 18:58:07.990435 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 125428 < 163840
2012-04-03 18:58:11.669083 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 110608 < 163840
2012-04-03 18:58:15.062007 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 294912 < 393216
2012-04-03 18:58:15.381063 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 360448 < 393216
2012-04-03 18:58:17.749287 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 18:58:17.968070 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 153376 < 163840
2012-04-03 18:58:18.894731 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 141352 < 163840
2012-04-03 18:58:18.938046 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 87176 < 393216
2012-04-03 18:58:27.165053 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 78448 < 393216
2012-04-03 18:58:29.123053 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 341988 < 393216
2012-04-03 18:58:35.568107 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 124416 < 393216
2012-04-03 18:58:38.145051 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 40508 < 163840
2012-04-03 18:58:43.914088 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 210912 < 393216
2012-04-03 18:58:46.629814 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 98304 < 163840
2012-04-03 18:58:49.950736 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 83136 < 163840
2012-04-03 18:58:50.478080 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 273740 < 393216
2012-04-03 18:58:51.347066 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 26620 < 393216
2012-04-03 18:58:52.535214 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 68720 < 163840
2012-04-03 18:58:55.066624 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:58:58.086032 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 71868 < 393216
2012-04-03 18:59:00.464168 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:59:05.801297 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:59:07.041074 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 243832 < 393216
2012-04-03 18:59:07.908049 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 127640 < 163840
2012-04-03 18:59:08.510060 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 0 < 393216
2012-04-03 18:59:10.475084 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 320224 < 393216
2012-04-03 18:59:12.196076 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 278172 < 393216
2012-04-03 18:59:15.010062 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 223492 < 393216
2012-04-03 18:59:16.530996 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:59:21.869161 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:59:22.684265 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 163620 < 163840
2012-04-03 18:59:26.140469 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 151336 < 163840
2012-04-03 18:59:28.163016 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 375880 < 393216
2012-04-03 18:59:35.080233 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 119492 < 163840
2012-04-03 18:59:36.252260 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 119972 < 163840
2012-04-03 18:59:37.978712 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:59:42.410049 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 368580 < 393216
2012-04-03 18:59:42.725081 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 40900 < 393216
2012-04-03 18:59:44.801087 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 196608 < 393216
2012-04-03 18:59:49.308032 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 161764 < 163840
2012-04-03 18:59:51.404142 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 382040 < 393216
2012-04-03 18:59:53.015052 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 222752 < 393216
2012-04-03 18:59:54.045005 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 18:59:57.305043 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 131072 < 393216
2012-04-03 18:59:58.773056 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 65536 < 393216
2012-04-03 19:00:04.775933 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:00:05.739879 N [1116/1168] Expire autoexpire.cpp:640 (SendDeleteMessages) - Expiring 0 MB for 1050 at 2012-04-03T18:57:46 => "Get Smart"
2012-04-03 19:00:06.808106 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 327680 < 393216
2012-04-03 19:00:06.859262 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 156164 < 163840
2012-04-03 19:00:09.324088 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 204640 < 393216
2012-04-03 19:00:13.920088 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 213220 < 393216
2012-04-03 19:00:16.083081 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 0 < 393216
2012-04-03 19:00:20.543073 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 206428 < 393216
2012-04-03 19:00:20.929833 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 115904 < 163840
2012-04-03 19:00:21.154046 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 327680 < 393216
2012-04-03 19:00:24.185619 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 149584 < 163840
2012-04-03 19:00:26.168977 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:00:27.451916 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 145608 < 163840
2012-04-03 19:00:31.566311 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:00:32.939072 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 56712 < 393216
2012-04-03 19:00:36.147555 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 96804 < 163840
2012-04-03 19:00:36.282518 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 381168 < 393216
2012-04-03 19:00:36.903427 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:00:39.478604 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 158064 < 163840
2012-04-03 19:00:39.612100 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 0 < 393216
2012-04-03 19:00:44.463055 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 138384 < 393216
2012-04-03 19:00:49.432099 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 281496 < 393216
2012-04-03 19:00:54.161082 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 131072 < 393216
2012-04-03 19:00:57.224425 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 137200 < 163840
2012-04-03 19:01:14.457078 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:01:19.484094 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 173720 < 393216
2012-04-03 19:01:29.751104 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 25736 < 163840
2012-04-03 19:01:30.525340 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:01:31.826072 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 342960 < 393216
2012-04-03 19:01:32.928065 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 106668 < 393216
2012-04-03 19:01:34.128771 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 60084 < 163840
2012-04-03 19:01:35.857527 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:01:38.135072 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 267624 < 393216
2012-04-03 19:01:43.936095 I [1116/1452] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1005_20120403182305.mpg): Waited 0.2 seconds for data 
			to become available... 129108 < 163840
2012-04-03 19:01:49.548111 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 65536 < 393216
2012-04-03 19:01:50.980198 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 4 min
2012-04-03 19:01:53.075621 W [1116/1155] TVRecEvent ThreadedFileWriter.cpp:301 (Flush) - TFW(/var/video/video2/video2/1005_20120403182305.mpg:117): Taking a long time to flush.. buffer size 0
2012-04-03 19:01:53.716779 I [1116/1395] DVBRead tv_rec.cpp:3253 (RingBufferChanged) - TVRec(7): RingBufferChanged()
2012-04-03 19:01:53.717265 I [1116/1395] DVBRead tv_rec.cpp:812 (FinishedRecording) - TVRec(7): FinishedRecording(1005_2012-04-03T18:23:05) damaged recq:<RecordingQuality overall_score="0" key="1005_2012-04-03T18:23:05" countinuity_error_count="6" packet_count="8798960">
    <Gap start="2012-04-03T18:00:00" end="2012-04-03T18:23:05" duration="1385" />
    <Gap start="2012-04-03T18:49:40" end="2012-04-03T18:49:40" duration="0" />
</RecordingQuality>

2012-04-03 19:01:54.436669 I [1116/1196] Socket mythdbcon.cpp:395 (PurgeIdleConnections) - New DB connection, total: 22
2012-04-03 19:01:54.442208 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 19:01:54.442216 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 19:01:54.451964 I [1116/1452] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 19:01:54.451969 I [1116/1452] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: michiru as a remote file transfer
2012-04-03 19:01:55.181389 I [1116] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(7:/dev/dvb/adapter1/frontend0): GetKeyframePositions(0,9223372036854775807,#3) out of 3
2012-04-03 19:02:15.800147 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 113804 < 393216
2012-04-03 19:02:18.730055 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:02:28.560135 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 127816 < 393216
2012-04-03 19:02:30.332275 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 89992 < 393216
2012-04-03 19:02:38.138093 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 131072 < 393216
2012-04-03 19:02:40.221481 I [1116/1166] Scheduler scheduler.cpp:2011 (HandleReschedule) - Reschedule requested for id -1.
2012-04-03 19:02:40.223080 I [1116/1385] ProcessRequest ringbuffer.cpp:1085 (WaitForAvail) - RingBuf(/var/video/video2/video2/1050_20120403185747.mpg): Waited 0.2 seconds for data 
			to become available... 262144 < 393216
2012-04-03 19:02:41.586926 I [1116/1166] Scheduler scheduler.cpp:2069 (HandleReschedule) - Scheduled 64 items in 1.4 = 0.99 match + 0.37 place
2012-04-03 19:02:42.460359 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from WatchingLiveTV to None
2012-04-03 19:02:42.591236 I [1116/1155] TVRecEvent tv_rec.cpp:812 (FinishedRecording) - TVRec(7): FinishedRecording(1005_2012-04-03T19:01:50) damaged recq:<RecordingQuality overall_score="0" key="1005_2012-04-03T19:01:50" countinuity_error_count="0" packet_count="185124">
    <Gap start="2012-04-03T19:02:38" end="2012-04-03T19:30:00" duration="1642" />
</RecordingQuality>

2012-04-03 19:02:42.687350 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 19:02:42.687357 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: michiru as a client (events: 0)
2012-04-03 19:02:42.792406 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from None to WatchingLiveTV
2012-04-03 19:02:42.794553 I [1116/1155] TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) - TVRec(7): HW Tuner: 7->7
2012-04-03 19:02:43.353943 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 4 min
2012-04-03 19:02:43.543915 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from WatchingLiveTV to None
2012-04-03 19:02:44.953921 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 7 min
2012-04-03 19:02:45.989927 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 19:02:45.989934 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 0)
2012-04-03 19:02:45.991340 I [1116] ProcessRequest mainserver.cpp:1475 (HandleAnnounce) - MainServer::HandleAnnounce FileTransfer
2012-04-03 19:02:45.991345 I [1116] ProcessRequest mainserver.cpp:1477 (HandleAnnounce) - adding: naru as a remote file transfer
2012-04-03 19:02:46.988569 W [1116/1157] TVRecEvent ThreadedFileWriter.cpp:301 (Flush) - TFW(/var/video/video2/video2/1050_20120403185747.mpg:194): Taking a long time to flush.. buffer size 0
2012-04-03 19:02:47.239231 I [1116/1395] DVBRead tv_rec.cpp:3253 (RingBufferChanged) - TVRec(8): RingBufferChanged()
2012-04-03 19:02:47.239505 I [1116/1395] DVBRead tv_rec.cpp:812 (FinishedRecording) - TVRec(8): FinishedRecording(1050_2012-04-03T18:57:47) damaged recq:<RecordingQuality overall_score="0" key="1050_2012-04-03T18:57:47" countinuity_error_count="0" packet_count="2490902">
    <Gap start="2012-04-03T18:32:00" end="2012-04-03T18:57:47" duration="1547" />
</RecordingQuality>

2012-04-03 19:02:47.930570 I [1116/1385] ProcessRequest recorderbase.cpp:386 (GetKeyframePositions) - RecBase(8:/dev/dvb/adapter1/frontend0): GetKeyframePositions(0,9223372036854775807,#1) out of 1
2012-04-03 19:03:04.957997 N [1116/1168] Expire autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 7 min
2012-04-03 19:03:19.750879 I [1116/1167] HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) - Running housekeeping thread
2012-04-03 19:03:28.868291 I [1116/1157] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(8): Changing from WatchingLiveTV to None
2012-04-03 19:03:28.888513 E [1116/1395] DVBRead dvbstreamhandler.cpp:214 (RunTS) - DVBSH(/dev/dvb/adapter1/frontend0): Device EOF detected
2012-04-03 19:03:29.005895 I [1116/1157] TVRecEvent tv_rec.cpp:812 (FinishedRecording) - TVRec(8): FinishedRecording(1050_2012-04-03T19:02:44) damaged recq:<RecordingQuality overall_score="0" key="1050_2012-04-03T19:02:44" countinuity_error_count="0" packet_count="347939">
    <Gap start="2012-04-03T19:03:26" end="2012-04-03T19:33:26" duration="1800" />
</RecordingQuality>

2012-04-03 19:03:29.165853 I [1116] ProcessRequest mainserver.cpp:1360 (HandleAnnounce) - MainServer::ANN Playback
2012-04-03 19:03:29.165861 I [1116] ProcessRequest mainserver.cpp:1362 (HandleAnnounce) - adding: naru as a client (events: 0)
2012-04-03 19:03:29.196847 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from None to WatchingLiveTV
2012-04-03 19:03:29.198023 I [1116/1155] TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) - TVRec(7): HW Tuner: 7->7
2012-04-03 19:03:30.302068 N [1116/1116] CoreContext autoexpire.cpp:263 (CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 17.0 GB w/freq: 7 min
2012-04-03 19:03:30.442280 I [1116/1155] TVRecEvent tv_rec.cpp:1014 (HandleStateChange) - TVRec(7): Changing from WatchingLiveTV to None
2012-04-03 19:04:04.304878 N [1116/1168] Expire autoexpire.cpp:640 (SendDeleteMessages) - Expiring 33 MB for 1005 at 2012-04-03T19:01:50 => Unknown
2012-04-03 19:04:04.304929 N [1116/1168] Expire autoexpire.cpp:640 (SendDeleteMessages) - Expiring 0 MB for 1005 at 2012-04-03T19:02:43 => "The Biggest Loser Singles"
