Ticket #8052: mythbackend_log.txt

File mythbackend_log.txt, 67.7 KB (added by jgarrard_myth@…, 6 years ago)

backend log of "Coronation Street" forever recording

Line 
1May 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..
2May 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
3May 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..
4May 27 20:56:49  mythlogserver: last message repeated 11 times
5May 27 20:57:36  mythlogserver: last message repeated 10 times
6May 27 20:57:36 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
7May 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..
8May 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
9May 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
10May 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..
11May 27 20:59:08  mythlogserver: last message repeated 2 times
12May 27 20:59:08 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
13May 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
14May 27 20:59:30 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1557 (HandlePendingRecordings) TVRec(8): ASK_RECORDING 8 29 0 0
15May 27 20:59:30 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1557 (HandlePendingRecordings) TVRec(4): ASK_RECORDING 4 29 0 0
16May 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
17May 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
18May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: E CoreContext mainserver.cpp:915 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
19May 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
20May 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
21May 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
22May 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...
23May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(8): RingBufferChanged()
24May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead recordinginfo.cpp:1131 (FinishedRecording) Finished recording Britain's Got Talent: channel 3003
25May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: E CoreContext mainserver.cpp:915 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
26May 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
27May 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
28May 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)
29May 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
30May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(4): RingBufferChanged()
31May 27 21:00:00 myth-server mythlogserver: mythbackend[1636]: I DVBRead recordinginfo.cpp:1131 (FinishedRecording) Finished recording Grand Designs: channel 11442
32May 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)
33May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2234 (HandleReschedule) Reschedule interrupted, will retry
34May 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
35May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for PLACE Interrupted
36May 27 21:00:01 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(13): RingBufferChanged()
37May 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>
38May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
39May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
40May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
41May 27 21:00:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
42May 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
43May 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..
44May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
45May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
46May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
47May 27 21:00:03 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
48May 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..
49May 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..
50May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
51May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
52May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
53May 27 21:00:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
54May 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
55May 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
56May 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
57May 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
58May 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
59May 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
60May 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
61May 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
62May 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
63May 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
64May 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
65May 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
66May 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
67May 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
68May 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
69May 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
70May 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
71May 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
72May 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
73May 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
74May 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
75May 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
76May 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
77May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
78May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
79May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
80May 27 21:00:25 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
81May 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..
82May 27 21:00:33  mythlogserver: last message repeated 11 times
83May 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
84May 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..
85May 27 21:01:08  mythlogserver: last message repeated 44 times
86May 27 21:01:08 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
87May 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
88May 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..
89May 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
90May 27 21:01:29 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
91May 27 21:01:30 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
92May 27 21:01:30 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
93May 27 21:01:30 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
94May 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..
95May 27 21:01:47  mythlogserver: last message repeated 3 times
96May 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
97May 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..
98May 27 21:02:32  mythlogserver: last message repeated 16 times
99May 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
100May 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..
101May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
102May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
103May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
104May 27 21:02:33 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
105May 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..
106May 27 21:02:36  mythlogserver: last message repeated 2 times
107May 27 21:02:36 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
108May 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..
109May 27 21:03:38  mythlogserver: last message repeated 21 times
110May 27 21:04:01  mythlogserver: last message repeated 3 times
111May 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
112May 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..
113May 27 21:04:42  mythlogserver: last message repeated 11 times
114May 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
115May 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..
116May 27 21:06:14  mythlogserver: last message repeated 10 times
117May 27 21:07:06  mythlogserver: last message repeated 5 times
118May 27 21:07:06 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
119May 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
120May 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
121May 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
122May 27 21:07:42 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
123May 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..
124May 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..
125May 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
126May 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
127May 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..
128May 27 21:09:07  mythlogserver: last message repeated 4 times
129May 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
130May 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
131May 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..
132May 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..
133May 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
134May 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
135May 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
136May 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
137May 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..
138May 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
139May 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..
140May 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..
141May 27 21:12:47 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
142May 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..
143May 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
144May 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..
145May 27 21:14:22  mythlogserver: last message repeated 13 times
146May 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
147May 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
148May 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..
149May 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..
150May 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
151May 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..
152May 27 21:15:10  mythlogserver: last message repeated 4 times
153May 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
154May 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..
155May 27 21:16:05  mythlogserver: last message repeated 12 times
156May 27 21:16:05 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
157May 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
158May 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..
159May 27 21:16:27  mythlogserver: last message repeated 5 times
160May 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
161May 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
162May 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
163May 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..
164May 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
165May 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..
166May 27 21:17:51  mythlogserver: last message repeated 35 times
167May 27 21:17:51 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
168May 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..
169May 27 21:18:15  mythlogserver: last message repeated 6 times
170May 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
171May 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
172May 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..
173May 27 21:18:49  mythlogserver: last message repeated 4 times
174May 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
175May 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..
176May 27 21:19:42  mythlogserver: last message repeated 4 times
177May 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
178May 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..
179May 27 21:20:52  mythlogserver: last message repeated 8 times
180May 27 21:20:52 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1043 (HandleStateChange) TVRec(13): Changing from WatchingLiveTV to None
181May 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>
182May 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.
183May 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.
184May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
185May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
186May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
187May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
188May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
189May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
190May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
191May 27 21:20:54 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
192May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
193May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: Myth-FE as a client (events: 0)
194May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1043 (HandleStateChange) TVRec(13): Changing from None to WatchingLiveTV
195May 27 21:21:19 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:3562 (TuningCheckForHWChange) TVRec(13): HW Tuner: 13->13
196May 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
197May 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
198May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
199May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
200May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
201May 27 21:21:21 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
202May 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
203May 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
204May 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
205May 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
206May 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
207May 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
208May 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
209May 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..
210May 27 21:22:10  mythlogserver: last message repeated 7 times
211May 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
212May 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..
213May 27 21:22:42  mythlogserver: last message repeated 4 times
214May 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"
215May 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..
216May 27 21:22:53 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
217May 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..
218May 27 21:24:01  mythlogserver: last message repeated 25 times
219May 27 21:24:29  mythlogserver: last message repeated 7 times
220May 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
221May 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..
222May 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
223May 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..
224May 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
225May 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..
226May 27 21:25:32  mythlogserver: last message repeated 4 times
227May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
228May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
229May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
230May 27 21:25:32 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
231May 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
232May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
233May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
234May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
235May 27 21:25:38 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
236May 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..
237May 27 21:26:44  mythlogserver: last message repeated 6 times
238May 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
239May 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..
240May 27 21:27:54  mythlogserver: last message repeated 9 times
241May 27 21:27:54 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
242May 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..
243May 27 21:28:21  mythlogserver: last message repeated 4 times
244May 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
245May 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..
246May 27 21:28:42  mythlogserver: last message repeated 3 times
247May 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
248May 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..
249May 27 21:29:01  mythlogserver: last message repeated 4 times
250May 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
251May 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..
252May 27 21:29:28  mythlogserver: last message repeated 3 times
253May 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
254May 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..
255May 27 21:29:42  mythlogserver: last message repeated 21 times
256May 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
257May 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..
258May 27 21:30:00  mythlogserver: last message repeated 22 times
259May 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
260May 27 21:30:01 myth-server mythlogserver: mythbackend[1636]: I DVBRead tv_rec.cpp:3359 (RingBufferChanged) TVRec(13): RingBufferChanged()
261May 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>
262May 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..
263May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
264May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
265May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
266May 27 21:30:02 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
267May 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..
268May 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..
269May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
270May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
271May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
272May 27 21:30:04 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
273May 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
274May 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
275May 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
276May 27 21:30:17 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:3562 (TuningCheckForHWChange) TVRec(13): HW Tuner: 13->13
277May 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
278May 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)
279May 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)
280May 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)
281May 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)
282May 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)
283May 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)
284May 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)
285May 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)
286May 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)
287May 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)
288May 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)
289May 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)
290May 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)
291May 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)
292May 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)
293May 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)
294May 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)
295May 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)
296May 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)
297May 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)
298May 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)
299May 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)
300May 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)
301May 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)
302May 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)
303May 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)
304May 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)
305May 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)
306May 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)
307May 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)
308May 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
309May 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..
310May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
311May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
312May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
313May 27 21:30:18 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
314May 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..
315May 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..
316May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Playback
317May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-fe as a client (events: 0)
318May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1510 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
319May 27 21:30:20 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1512 (HandleAnnounce) adding: myth-fe as a remote file transfer
320May 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
321May 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..
322May 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
323May 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
324May 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
325May 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
326May 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
327May 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
328May 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
329May 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
330May 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
331May 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..
332May 27 21:31:06  mythlogserver: last message repeated 19 times
333May 27 21:31:06 myth-server mythlogserver: mythbackend[1636]: I Scheduler scheduler.cpp:2130 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - EITScanner
334May 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..
335May 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..
336May 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
337May 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..
338May 27 21:32:00  mythlogserver: last message repeated 33 times
339May 27 21:32:00 myth-server mythlogserver: mythbackend[1636]: I TVRecEvent tv_rec.cpp:1043 (HandleStateChange) TVRec(8): Changing from RecordingOnly to None
340May 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..
341May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
342May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 0)
343May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
344May 27 21:32:01 myth-server mythlogserver: mythbackend[1636]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: myth-server as a client (events: 1)
345May 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..
346May 27 21:32:41  mythlogserver: last message repeated 29 times
347May 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"
348May 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"
349May 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..
350May 27 21:32:52  mythlogserver: last message repeated 10 times
351May 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
352May 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..
353May 27 21:32:58  mythlogserver: last message repeated 5 times
354May 27 21:32:58 myth-server mythlogserver: mythbackend[1636]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
355May 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..
356May 27 21:33:54  mythlogserver: last message repeated 39 times
357May 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
358May 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..
359May 27 21:34:41  mythlogserver: last message repeated 22 times
360May 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
361May 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..
362May 27 21:34:49  mythlogserver: last message repeated 3 times
363May 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
364May 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..
365May 27 21:35:35  mythlogserver: last message repeated 40 times
366May 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
367May 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..
368May 27 21:36:37  mythlogserver: last message repeated 46 times
369May 27 21:37:39  mythlogserver: last message repeated 32 times
370May 27 21:38:05  mythlogserver: last message repeated 7 times