May 27 20:55:40 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 20:55:43 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 20:55:48 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 20:56:49  mythlogserver: last message repeated 11 times
May 27 20:57:36  mythlogserver: last message repeated 10 times
May 27 20:57:36 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 20:57:45 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 20:58:10 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 66044 < 98304
May 27 20:58:26 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 94004 < 98304
May 27 20:58:59 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 20:59:08  mythlogserver: last message repeated 2 times
May 27 20:59:08 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
May 27 20:59:09 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2243 (HandleReschedule) Scheduled 164 items in 0.7 = 0.00 match + 0.00 check + 0.71 place
May 27 20:59:30 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1557 (HandlePendingRecordings) TVRec(8): ASK_RECORDING 8 29 0 0
May 27 20:59:30 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1557 (HandlePendingRecordings) TVRec(4): ASK_RECORDING 4 29 0 0
May 27 20:59:43 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 20:59:45 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 87424 < 98304
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: E CoreContext mainserver.cpp:915 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: N Scheduler autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2651 (HandleRecordingStatusChange) Started recording: "Coronation Street": channel 3003 on cardid 8, sourceid 3
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: E TVRecEvent recordinginfo.cpp:984 (InsertProgram) RecordingInfo::InsertProgram(ProgramInfo(3003_20130527200000.mpg): channame(ITV) startts(Mon May 27 20:00:00 2013) endts(Mon May 27 20:30:00 2013)#012             recstartts(Mon May 27 20:00:00 2013) recendts(Mon May 27 20:30:00 2013)#012             title(Coronation Street)): recording already exists...
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(8): RingBufferChanged()
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead recordinginfo.cpp:1131 (FinishedRecording) Finished recording Britain's Got Talent: channel 3003
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: E CoreContext mainserver.cpp:915 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: N Scheduler autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2651 (HandleRecordingStatusChange) Started recording: "Grand Designs": channel 11442 on cardid 4, sourceid 3
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I CoreContext scheduler.cpp:655 (UpdateRecStatus) Updating status for "Britain's Got Talent" on cardid 8 (Recording => Recorded)
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for CHECK -3 55 0 UpdateRecStatus2 | Britain's Got Talent |  | Ant and Dec host the first live semi-final, which sees nine of the 45 chosen acts compete to impress the judges and secure the all-important viewers' vote. At stake is the chance to appear at the Royal Variety Performance and win a life-changing cash prize of £250,000, but first the contestants have to win one of two places available in the final - and with the panel still allowed to buzz them off if things aren't going well, the pressure is on. The results are at 9.30pm. Continues tomorrow. | EP4997597287
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(4): RingBufferChanged()
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead recordinginfo.cpp:1131 (FinishedRecording) Finished recording Grand Designs: channel 11442
May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I CoreContext scheduler.cpp:655 (UpdateRecStatus) Updating status for "Grand Designs" on cardid 4 (Recording => Recorded)
May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2234 (HandleReschedule) Reschedule interrupted, will retry
May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for CHECK -3 73 0 UpdateRecStatus2 | Grand Designs |  | Kevin McCloud meets Claire Farrow and husband Ian Hogarth, who are trying to build a home containing a sauna, spa, dance floor and DJ booth on a small patch of land in London. They have applied for planning permission to include a basement and started work on the construction, but setbacks, including their digger smashing into a neighbour's wall and the re-emergence of the old river under the building, threaten to prevent them completing the project. | EP714169157b
May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for PLACE Interrupted
May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(13): RingBufferChanged()
May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:830 (FinishedRecording) TVRec(13): FinishedRecording(3003_2013-05-27T19:45:41Z) damaged recq:<RecordingQuality overall_score="0" key="3003_2013-05-27T19:45:41Z" countinuity_error_count="5" packet_count="1603499">#012    <Gap start="2013-05-27T18:30:00Z" end="2013-05-27T19:45:41Z" duration="4541" />#012</RecordingQuality>
May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2243 (HandleReschedule) Scheduled 161 items in 0.9 = 0.01 match + 0.02 check + 0.84 place
May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527194541.mpg): Taking too long to be allowed to read..
May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
May 27 21:00:05 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest recorderbase.cpp:395 (GetKeyframePositions) RecBase(13:/dev/dvb/adapter2/frontend0): GetKeyframePositions(31,9223372036854775807,#2) out of 4
May 27 21:00:05 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 209832 < 393216
May 27 21:00:06 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 275256 < 393216
May 27 21:00:06 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 340680 < 393216
May 27 21:00:06 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 199008 < 393216
May 27 21:00:06 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 264432 < 393216
May 27 21:00:06 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 329856 < 393216
May 27 21:00:07 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 230860 < 393216
May 27 21:00:07 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 296284 < 393216
May 27 21:00:08 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 141264 < 360448
May 27 21:00:08 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 205748 < 360448
May 27 21:00:08 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 250304 < 360448
May 27 21:00:09 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 216036 < 360448
May 27 21:00:09 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 280520 < 360448
May 27 21:00:09 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 345944 < 360448
May 27 21:00:09 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 158832 < 327680
May 27 21:00:10 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 214668 < 327680
May 27 21:00:10 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 317504 < 327680
May 27 21:00:11 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 223324 < 294912
May 27 21:00:11 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 223324 < 294912
May 27 21:00:16 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 139932 < 163840
May 27 21:00:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 140784 < 163840
May 27 21:00:22 myth-server mythlogserver: mythbackend[1636]: I Metadata_773 jobqueue.cpp:2156 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Britain's Got Talent" recorded from channel 3003 at 2013-05-27T18:30:00Z
May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:00:26 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:00:33  mythlogserver: last message repeated 11 times
May 27 21:00:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 30592 < 65536
May 27 21:00:34 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:01:08  mythlogserver: last message repeated 44 times
May 27 21:01:08 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
May 27 21:01:09 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2243 (HandleReschedule) Scheduled 161 items in 1.8 = 0.63 match + 0.56 check + 0.56 place
May 27 21:01:13 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:01:27 myth-server mythlogserver: mythbackend[1636]: I Metadata_774 jobqueue.cpp:2156 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Grand Designs" recorded from channel 11442 at 2013-05-27T18:55:00Z
May 27 21:01:29 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:01:30 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:01:30 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:01:30 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:01:35 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:01:47  mythlogserver: last message repeated 3 times
May 27 21:01:47 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 48776 < 65536
May 27 21:01:50 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:02:32  mythlogserver: last message repeated 16 times
May 27 21:02:32 myth-server mythlogserver: mythbackend[1636]: I Commflag_777 jobqueue.cpp:2281 (DoFlagCommercialsThread) JobQueue: Commercial Detection Starting for "Britain's Got Talent" recorded from channel 3003 at 2013-05-27T18:30:00Z
May 27 21:02:32 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:02:34 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:02:36  mythlogserver: last message repeated 2 times
May 27 21:02:36 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:02:37 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:03:38  mythlogserver: last message repeated 21 times
May 27 21:04:01  mythlogserver: last message repeated 3 times
May 27 21:04:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 39548 < 65536
May 27 21:04:03 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:04:42  mythlogserver: last message repeated 11 times
May 27 21:04:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:04:51 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:06:14  mythlogserver: last message repeated 10 times
May 27 21:07:06  mythlogserver: last message repeated 5 times
May 27 21:07:06 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
May 27 21:07:08 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2243 (HandleReschedule) Scheduled 161 items in 1.9 = 0.70 match + 0.60 check + 0.57 place
May 27 21:07:11 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 72036 < 98304
May 27 21:07:41 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 5188 < 98304
May 27 21:07:42 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:07:51 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:07:58 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:08:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 27068 < 65536
May 27 21:08:22 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 86840 < 98304
May 27 21:08:25 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:09:07  mythlogserver: last message repeated 4 times
May 27 21:09:07 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 96952 < 98304
May 27 21:09:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:09:44 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:09:50 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:10:23 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 49508 < 65536
May 27 21:10:31 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 80604 < 98304
May 27 21:10:47 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 94104 < 98304
May 27 21:10:50 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 86488 < 98304
May 27 21:12:07 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:12:17 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 96576 < 98304
May 27 21:12:34 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:12:46 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:12:47 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:13:01 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:13:14 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 95460 < 98304
May 27 21:13:27 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:14:22  mythlogserver: last message repeated 13 times
May 27 21:14:22 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 92832 < 98304
May 27 21:14:26 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 93380 < 98304
May 27 21:14:40 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:14:41 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:14:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:14:44 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:15:10  mythlogserver: last message repeated 4 times
May 27 21:15:10 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 94992 < 98304
May 27 21:15:14 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:16:05  mythlogserver: last message repeated 12 times
May 27 21:16:05 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
May 27 21:16:07 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2243 (HandleReschedule) Scheduled 161 items in 2.2 = 0.95 match + 0.60 check + 0.62 place
May 27 21:16:13 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:16:27  mythlogserver: last message repeated 5 times
May 27 21:16:27 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 22408 < 65536
May 27 21:16:27 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 28988 < 65536
May 27 21:16:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 29072 < 98304
May 27 21:16:53 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:16:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 63016 < 65536
May 27 21:16:56 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:17:51  mythlogserver: last message repeated 35 times
May 27 21:17:51 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:17:52 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:18:15  mythlogserver: last message repeated 6 times
May 27 21:18:15 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 35836 < 65536
May 27 21:18:16 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 35836 < 65536
May 27 21:18:20 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:18:49  mythlogserver: last message repeated 4 times
May 27 21:18:49 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 32964 < 65536
May 27 21:18:52 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:19:42  mythlogserver: last message repeated 4 times
May 27 21:19:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:20:11 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527200001.mpg): Taking too long to be allowed to read..
May 27 21:20:52  mythlogserver: last message repeated 8 times
May 27 21:20:52 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1043 (HandleStateChange) TVRec(13): Changing from WatchingLiveTV to None
May 27 21:20:52 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:830 (FinishedRecording) TVRec(13): FinishedRecording(3003_2013-05-27T20:00:01Z) damaged recq:<RecordingQuality overall_score="0" key="3003_2013-05-27T20:00:01Z" countinuity_error_count="0" packet_count="2244708">#012    <Gap start="2013-05-27T20:20:47Z" end="2013-05-27T20:30:00Z" duration="552" />#012</RecordingQuality>
May 27 21:20:52 myth-server mythlogserver: mythbackend[1636]: E ProcessRequest programinfo.cpp:2284 (GetPlaybackURL) ProgramInfo(3007_20130508222500.mpg): GetPlaybackURL: '3007_20130508222500.mpg' should be local, but it can not be found.
May 27 21:20:52 myth-server mythlogserver: mythbackend[1636]: E ProcessRequest programinfo.cpp:2284 (GetPlaybackURL) ProgramInfo(3007_20130508220000.mpg): GetPlaybackURL: '3007_20130508220000.mpg' should be local, but it can not be found.
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: Myth-FE as a client (events: 0)
May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1043 (HandleStateChange) TVRec(13): Changing from None to WatchingLiveTV
May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:3562 (TuningCheckForHWChange) TVRec(13): HW Tuner: 13->13
May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:21:20 myth-server mythlogserver: mythbackend[1636]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
May 27 21:21:24 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest recorderbase.cpp:395 (GetKeyframePositions) RecBase(13:/dev/dvb/adapter2/frontend0): GetKeyframePositions(52,9223372036854775807,#1) out of 4
May 27 21:21:24 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 134624 < 262144
May 27 21:21:24 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 185008 < 262144
May 27 21:21:24 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.5 seconds for data #012#011#011#011to become available... 239152 < 262144
May 27 21:21:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest recorderbase.cpp:395 (GetKeyframePositions) RecBase(13:/dev/dvb/adapter2/frontend0): GetKeyframePositions(97,9223372036854775807,#0) out of 5
May 27 21:21:26 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 188624 < 229376
May 27 21:21:27 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 154096 < 163840
May 27 21:21:30 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:22:10  mythlogserver: last message repeated 7 times
May 27 21:22:10 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 26124 < 65536
May 27 21:22:11 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:22:42  mythlogserver: last message repeated 4 times
May 27 21:22:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:641 (SendDeleteMessages) Expiring 0 MB for 3003 at 2013-05-27T20:21:19Z => "Coronation Street"
May 27 21:22:50 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:22:53 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:22:53 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:24:01  mythlogserver: last message repeated 25 times
May 27 21:24:29  mythlogserver: last message repeated 7 times
May 27 21:24:29 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 37652 < 65536
May 27 21:24:34 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:24:34 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 73488 < 98304
May 27 21:24:40 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:24:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:24:54 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:25:32  mythlogserver: last message repeated 4 times
May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:25:37 myth-server mythlogserver: mythbackend[1636]: I Commflag_778 jobqueue.cpp:2281 (DoFlagCommercialsThread) JobQueue: Commercial Detection Starting for "Grand Designs" recorded from channel 11442 at 2013-05-27T18:55:00Z
May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:26:14 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:26:44  mythlogserver: last message repeated 6 times
May 27 21:26:44 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 18764 < 98304
May 27 21:27:03 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:27:54  mythlogserver: last message repeated 9 times
May 27 21:27:54 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:27:55 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:28:21  mythlogserver: last message repeated 4 times
May 27 21:28:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 75752 < 98304
May 27 21:28:35 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:28:42  mythlogserver: last message repeated 3 times
May 27 21:28:42 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 43952 < 65536
May 27 21:28:46 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:29:01  mythlogserver: last message repeated 4 times
May 27 21:29:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 46696 < 65536
May 27 21:29:21 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:29:28  mythlogserver: last message repeated 3 times
May 27 21:29:28 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 47524 < 65536
May 27 21:29:29 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:29:42  mythlogserver: last message repeated 21 times
May 27 21:29:42 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:29:42 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:30:00  mythlogserver: last message repeated 22 times
May 27 21:30:00 myth-server mythlogserver: mythbackend[1636]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:30:01 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(13): RingBufferChanged()
May 27 21:30:01 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:830 (FinishedRecording) TVRec(13): FinishedRecording(3003_2013-05-27T20:21:20Z) damaged recq:<RecordingQuality overall_score="0" key="3003_2013-05-27T20:21:20Z" countinuity_error_count="0" packet_count="878285">#012    <Gap start="2013-05-27T20:00:00Z" end="2013-05-27T20:21:20Z" duration="1280" />#012</RecordingQuality>
May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:30:03 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527202120.mpg): Taking too long to be allowed to read..
May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527203000.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 201140 < 262144
May 27 21:30:06 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest recorderbase.cpp:395 (GetKeyframePositions) RecBase(13:/dev/dvb/adapter2/frontend0): GetKeyframePositions(85,9223372036854775807,#1) out of 6
May 27 21:30:08 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3003_20130527203000.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 231164 < 262144
May 27 21:30:17 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:3562 (TuningCheckForHWChange) TVRec(13): HW Tuner: 13->13
May 27 21:30:17 myth-server mythlogserver: mythbackend[1636]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26240) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27584) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28032) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(25728) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27136) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28288) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27296) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26304) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27456) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26176) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(25792) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26624) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28416) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28384) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26560) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28352) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27232) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(25920) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27520) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27168) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27040) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26368) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26752) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27712) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27840) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27808) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28320) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(26688) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(28160) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: E DVBRead dtvsignalmonitor.cpp:347 (HandlePMT) DTVSM(/dev/dvb/adapter2/frontend0): Wrong PMT; pmt->pn(27904) desired(25664)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 4 min
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527203000.mpg): Taking too long to be allowed to read..
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:30:19 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527203000.mpg): Taking too long to be allowed to read..
May 27 21:30:19 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3003_20130527203000.mpg): Taking too long to be allowed to read..
May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
May 27 21:30:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 205996 < 262144
May 27 21:30:24 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:30:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 133692 < 163840
May 27 21:30:26 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest recorderbase.cpp:395 (GetKeyframePositions) RecBase(13:/dev/dvb/adapter2/frontend0): GetKeyframePositions(145,9223372036854775807,#0) out of 6
May 27 21:30:26 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest recorderbase.cpp:395 (GetKeyframePositions) RecBase(13:/dev/dvb/adapter2/frontend0): GetKeyframePositions(145,9223372036854775807,#0) out of 6
May 27 21:30:27 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 239772 < 262144
May 27 21:30:29 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 160856 < 262144
May 27 21:30:29 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 226280 < 262144
May 27 21:30:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 129436 < 196608
May 27 21:30:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 177752 < 196608
May 27 21:30:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 124536 < 163840
May 27 21:30:35 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:31:06  mythlogserver: last message repeated 19 times
May 27 21:31:06 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
May 27 21:31:06 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:31:07 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:31:08 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2243 (HandleReschedule) Scheduled 161 items in 1.9 = 0.65 match + 0.59 check + 0.62 place
May 27 21:31:08 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:32:00  mythlogserver: last message repeated 33 times
May 27 21:32:00 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1043 (HandleStateChange) TVRec(8): Changing from RecordingOnly to None
May 27 21:32:00 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
May 27 21:32:02 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:32:41  mythlogserver: last message repeated 29 times
May 27 21:32:41 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:641 (SendDeleteMessages) Expiring 5 MB for 3003 at 2013-05-27T20:30:00Z => "Britain's Got Talent Results"
May 27 21:32:41 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:641 (SendDeleteMessages) Expiring 0 MB for 3018 at 2013-05-27T20:30:17Z => "Kourtney and Kim Take New York":"Voices from Beyond"
May 27 21:32:42 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:32:52  mythlogserver: last message repeated 10 times
May 27 21:32:52 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 51436 < 65536
May 27 21:32:53 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:32:58  mythlogserver: last message repeated 5 times
May 27 21:32:58 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
May 27 21:32:59 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:33:54  mythlogserver: last message repeated 39 times
May 27 21:33:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 45380 < 65536
May 27 21:33:59 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:34:41  mythlogserver: last message repeated 22 times
May 27 21:34:41 myth-server mythlogserver: mythbackend[1636]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 7 min
May 27 21:34:44 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:34:49  mythlogserver: last message repeated 3 times
May 27 21:34:49 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 46012 < 65536
May 27 21:34:50 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:35:35  mythlogserver: last message repeated 40 times
May 27 21:35:35 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest ringbuffer.cpp:1098 (WaitForAvail) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Waited 0.2 seconds for data #012#011#011#011to become available... 47424 < 65536
May 27 21:35:36 myth-server mythlogserver: mythbackend[1636]: W ProcessRequest ringbuffer.cpp:1035 (WaitForReadsAllowed) RingBuf(/mnt/media-3TB/liveTV/3018_20130527203018.mpg): Taking too long to be allowed to read..
May 27 21:36:37  mythlogserver: last message repeated 46 times
May 27 21:37:39  mythlogserver: last message repeated 32 times
May 27 21:38:05  mythlogserver: last message repeated 7 times