1 | 2012-12-02 06:31:35.739881 C mythbackend version: fixes/0.26 [v0.26.0] www.mythtv.org |
---|
2 | 2012-12-02 06:31:35.739914 C Qt version: compile: 4.8.3, runtime: 4.8.3 |
---|
3 | 2012-12-02 06:31:35.739920 N Enabled verbose msgs: general channel record |
---|
4 | 2012-12-02 06:31:35.739931 N Setting Log Level to LOG_INFO |
---|
5 | 2012-12-02 06:31:35.740442 I Added logging to the console |
---|
6 | 2012-12-02 06:31:35.741040 I Setup Interrupt handler |
---|
7 | 2012-12-02 06:31:35.741054 I Setup Terminated handler |
---|
8 | 2012-12-02 06:31:35.741065 I Setup Segmentation fault handler |
---|
9 | 2012-12-02 06:31:35.741088 I Setup Aborted handler |
---|
10 | 2012-12-02 06:31:35.741098 I Setup Bus error handler |
---|
11 | 2012-12-02 06:31:35.741108 I Setup Floating point exception handler |
---|
12 | 2012-12-02 06:31:35.741118 I Setup Illegal instruction handler |
---|
13 | 2012-12-02 06:31:35.741131 I Setup Real-time signal 0 handler |
---|
14 | 2012-12-02 06:31:35.741247 N Using runtime prefix = /usr |
---|
15 | 2012-12-02 06:31:35.741287 N Using configuration directory = /root/.mythtv |
---|
16 | 2012-12-02 06:31:35.741389 I Assumed character encoding: en_US.UTF-8 |
---|
17 | 2012-12-02 06:31:35.741838 N Empty LocalHostName. |
---|
18 | 2012-12-02 06:31:35.741848 I Using localhost value of zeus |
---|
19 | 2012-12-02 06:31:35.754763 N Setting QT default locale to en_US |
---|
20 | 2012-12-02 06:31:35.754836 I Current locale en_US |
---|
21 | 2012-12-02 06:31:35.754889 N Reading locale defaults from /usr/share/mythtv//locales/en_us.xml |
---|
22 | 2012-12-02 06:31:35.763206 I Current MythTV Schema Version (DBSchemaVer): 1307 |
---|
23 | 2012-12-02 06:31:35.763893 I Loading en_us translation for module mythfrontend |
---|
24 | 2012-12-02 06:31:35.764664 N MythBackend: Starting up as the master server. |
---|
25 | 2012-12-02 06:31:35.769548 I TVRec(5): Start channel: 2. |
---|
26 | 2012-12-02 06:31:35.769734 I V4LChannel(/dev/video1): Device name 'Hauppauge WinTV-HVR2250' driver 'saa7164'. |
---|
27 | 2012-12-02 06:31:35.769758 I V4LChannel(/dev/video1): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 0 |
---|
28 | 2012-12-02 06:31:35.777862 I ChannelBase(5): Input #5: 'tuner' schan(2) sourceid(1) ccid(5) |
---|
29 | 2012-12-02 06:31:35.777875 I ChannelBase(5): Current Input #5: 'tuner' |
---|
30 | 2012-12-02 06:31:35.778436 I Global TVFormat Setting 'NTSC' |
---|
31 | 2012-12-02 06:31:35.778480 I V4LChannel(/dev/video1): Input #5: 'tuner' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
---|
32 | 2012-12-02 06:31:35.778496 I V4LChannel(/dev/video1): SetFormat(Default) fmt(NTSC) input(5) |
---|
33 | 2012-12-02 06:31:35.801230 I V4LChannel(/dev/video1): SetInputAndFormat(5, NTSC) (v4l v2) input_switch: 0 mode_switch: 1 |
---|
34 | 2012-12-02 06:31:35.803830 I V4LChannel(/dev/video1): SetFormat(NTSC) fmt(NTSC) input(5) |
---|
35 | 2012-12-02 06:31:35.804452 I Channel(/dev/video1)::SwitchToInput(in 5, '') |
---|
36 | 2012-12-02 06:31:35.805374 I V4LChannel(/dev/video1): SetInputAndFormat(5, NTSC) (v4l v2) input_switch: 0 mode_switch: 1 |
---|
37 | 2012-12-02 06:31:35.808471 I DTVChan(/dev/video1): SetChannelByString(2): |
---|
38 | 2012-12-02 06:31:35.809163 I V4LChannel(/dev/video1): SetFormat(Default) fmt(NTSC) input(5) |
---|
39 | 2012-12-02 06:31:35.809185 I Channel(/dev/video1)::Tune(2): curList[0].freq(55250) |
---|
40 | 2012-12-02 06:31:35.809194 I V4LChannel(/dev/video1): Tune(55250000, ) |
---|
41 | 2012-12-02 06:31:35.969940 I Added logging to mythlogserver at TCP:35327 |
---|
42 | 2012-12-02 06:31:36.097501 I Channel(/dev/video1)::Tune(): Frequency is now 55250000 |
---|
43 | 2012-12-02 06:31:36.097509 I DTVChan(/dev/video1): SetChannelByString(2): success |
---|
44 | 2012-12-02 06:31:36.107715 I TVRec(5): SetFlags(RunMainLoop,) -> RunMainLoop, |
---|
45 | 2012-12-02 06:31:36.107736 I TVRec(5): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
---|
46 | 2012-12-02 06:31:36.108552 I TVRec(6): Start channel: 63. |
---|
47 | 2012-12-02 06:31:36.108621 I V4LChannel(/dev/video2): Device name 'Hauppauge WinTV-HVR2250' driver 'saa7164'. |
---|
48 | 2012-12-02 06:31:36.108637 I V4LChannel(/dev/video2): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 0 |
---|
49 | 2012-12-02 06:31:36.116206 I ChannelBase(6): Input #6: 'tuner' schan(63) sourceid(1) ccid(6) |
---|
50 | 2012-12-02 06:31:36.116217 I ChannelBase(6): Current Input #6: 'tuner' |
---|
51 | 2012-12-02 06:31:36.116239 I Global TVFormat Setting 'NTSC' |
---|
52 | 2012-12-02 06:31:36.116267 I V4LChannel(/dev/video2): Input #6: 'tuner' schan(63) tun() v4l1(Unknown) v4l2(NTSC) |
---|
53 | 2012-12-02 06:31:36.116281 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(6) |
---|
54 | 2012-12-02 06:31:36.116964 I V4LChannel(/dev/video2): SetInputAndFormat(6, NTSC) (v4l v2) input_switch: 0 mode_switch: 1 |
---|
55 | 2012-12-02 06:31:36.120030 I V4LChannel(/dev/video2): SetFormat(NTSC) fmt(NTSC) input(6) |
---|
56 | 2012-12-02 06:31:36.120044 I Channel(/dev/video2)::SwitchToInput(in 6, '') |
---|
57 | 2012-12-02 06:31:36.120989 I V4LChannel(/dev/video2): SetInputAndFormat(6, NTSC) (v4l v2) input_switch: 0 mode_switch: 1 |
---|
58 | 2012-12-02 06:31:36.123773 I DTVChan(/dev/video2): SetChannelByString(63): |
---|
59 | 2012-12-02 06:31:36.124391 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(6) |
---|
60 | 2012-12-02 06:31:36.124411 I Channel(/dev/video2)::Tune(63): curList[61].freq(457250) |
---|
61 | 2012-12-02 06:31:36.124419 I V4LChannel(/dev/video2): Tune(457250000, ) |
---|
62 | 2012-12-02 06:31:36.417561 I Channel(/dev/video2)::Tune(): Frequency is now 457250000 |
---|
63 | 2012-12-02 06:31:36.417568 I DTVChan(/dev/video2): SetChannelByString(63): success |
---|
64 | 2012-12-02 06:31:36.423096 I TVRec(6): SetFlags(RunMainLoop,) -> RunMainLoop, |
---|
65 | 2012-12-02 06:31:36.423116 I TVRec(6): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
---|
66 | 2012-12-02 06:31:36.423860 I TVRec(7): Start channel: 108-246. |
---|
67 | 2012-12-02 06:31:36.428012 I DVBChan(7:/dev/dvb/adapter0/frontend0): Opening DVB channel |
---|
68 | 2012-12-02 06:31:36.430037 I DVBChan(7:/dev/dvb/adapter0/frontend0): Using DVB card /dev/dvb/adapter0/frontend0, with frontend 'LG Electronics LGDT3303 VSB/QAM Frontend'. |
---|
69 | 2012-12-02 06:31:36.432170 I ChannelBase(7): Input #7: 'DVBInput' schan(108-246) sourceid(4) ccid(7) |
---|
70 | 2012-12-02 06:31:36.432181 I ChannelBase(7): Current Input #7: 'DVBInput' |
---|
71 | 2012-12-02 06:31:36.432416 I DTVChan(/dev/dvb/adapter0/frontend0): SetChannelByString(108-246): |
---|
72 | 2012-12-02 06:31:36.434193 I DVBChan(7:/dev/dvb/adapter0/frontend0): 699000000 qam_256 a auto auto a a auto a v fec: auto msys: UNDEFINED rolloff: 0.35 |
---|
73 | 2012-12-02 06:31:36.434248 I DVBChan(7:/dev/dvb/adapter0/frontend0): Old Params: 0 auto a auto auto a a auto a v fec: auto msys: UNDEFINED rolloff: 0.35 |
---|
74 | DVBChan(7:/dev/dvb/adapter0/frontend0): New Params: 699000000 qam_256 a auto auto a a auto a v fec: auto msys: UNDEFINED rolloff: 0.35 |
---|
75 | 2012-12-02 06:31:36.434260 I DVBChan(7:/dev/dvb/adapter0/frontend0): Tune(): Tuning to 699000000Hz |
---|
76 | 2012-12-02 06:31:36.438194 I DVBChan: wait_for_backend: Status: |
---|
77 | 2012-12-02 06:31:36.438203 I DVBChan(7:/dev/dvb/adapter0/frontend0): Tune(): Frequency tuning successful. |
---|
78 | 2012-12-02 06:31:36.438212 I DTVChan(/dev/dvb/adapter0/frontend0): SetChannelByString(108-246): success |
---|
79 | 2012-12-02 06:31:36.438224 I DVBChan(7:/dev/dvb/adapter0/frontend0): Closing DVB channel |
---|
80 | 2012-12-02 06:31:36.438399 I TVRec(7): SetFlags(RunMainLoop,) -> RunMainLoop, |
---|
81 | 2012-12-02 06:31:36.438415 I TVRec(7): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
---|
82 | 2012-12-02 06:31:36.440012 I TVRec(8): Start channel: 106-317. |
---|
83 | 2012-12-02 06:31:36.442074 I DVBChan(8:/dev/dvb/adapter0/frontend0): Opening DVB channel |
---|
84 | 2012-12-02 06:31:36.442086 I DVBChan(7:/dev/dvb/adapter0/frontend0): Opening DVB channel |
---|
85 | 2012-12-02 06:31:36.444096 I DVBChan(7:/dev/dvb/adapter0/frontend0): Using DVB card /dev/dvb/adapter0/frontend0, with frontend 'LG Electronics LGDT3303 VSB/QAM Frontend'. |
---|
86 | 2012-12-02 06:31:36.446228 I ChannelBase(7): Input #7: 'DVBInput' schan(108-246) sourceid(4) ccid(7) |
---|
87 | 2012-12-02 06:31:36.446239 I ChannelBase(7): Current Input #7: 'DVBInput' |
---|
88 | 2012-12-02 06:31:36.448301 I ChannelBase(8): Input #8: 'DVBInput' schan(106-317) sourceid(4) ccid(8) |
---|
89 | 2012-12-02 06:31:36.448312 I ChannelBase(8): Current Input #8: 'DVBInput' |
---|
90 | 2012-12-02 06:31:36.448540 I DTVChan(/dev/dvb/adapter0/frontend0): SetChannelByString(106-317): |
---|
91 | 2012-12-02 06:31:36.449941 I DVBChan(8:/dev/dvb/adapter0/frontend0): 687000000 qam_256 a auto auto a a auto a v fec: auto msys: UNDEFINED rolloff: 0.35 |
---|
92 | 2012-12-02 06:31:36.449953 I DVBChan(8:/dev/dvb/adapter0/frontend0): tuning on slave channel |
---|
93 | 2012-12-02 06:31:36.450000 I DVBChan(7:/dev/dvb/adapter0/frontend0): Old Params: 699000000 qam_256 a auto auto a a auto a v fec: auto msys: UNDEFINED rolloff: 0.35 |
---|
94 | DVBChan(7:/dev/dvb/adapter0/frontend0): New Params: 687000000 qam_256 a auto auto a a auto a v fec: auto msys: UNDEFINED rolloff: 0.35 |
---|
95 | 2012-12-02 06:31:36.450011 I DVBChan(7:/dev/dvb/adapter0/frontend0): Tune(): Tuning to 687000000Hz |
---|
96 | 2012-12-02 06:31:36.453929 I DVBChan: wait_for_backend: Status: |
---|
97 | 2012-12-02 06:31:36.453938 I DVBChan(7:/dev/dvb/adapter0/frontend0): Tune(): Frequency tuning successful. |
---|
98 | 2012-12-02 06:31:36.453945 I DTVChan(/dev/dvb/adapter0/frontend0): SetChannelByString(106-317): success |
---|
99 | 2012-12-02 06:31:36.453955 I DVBChan(8:/dev/dvb/adapter0/frontend0): Closing DVB channel |
---|
100 | 2012-12-02 06:31:36.453963 I DVBChan(7:/dev/dvb/adapter0/frontend0): Closing DVB channel |
---|
101 | 2012-12-02 06:31:36.454133 I TVRec(8): SetFlags(RunMainLoop,) -> RunMainLoop, |
---|
102 | 2012-12-02 06:31:36.454149 I TVRec(8): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
---|
103 | 2012-12-02 06:31:36.524965 I Found 1 distinct programid authorities |
---|
104 | 2012-12-02 06:31:36.525247 I New static DB connectionSchedCon |
---|
105 | 2012-12-02 06:31:36.528916 I Listening on TCP 127.0.0.1:6544 |
---|
106 | 2012-12-02 06:31:36.528966 I Listening on TCP 192.168.2.4:6544 |
---|
107 | 2012-12-02 06:31:36.531065 I Listening on TCP [::1]:6544 |
---|
108 | 2012-12-02 06:31:36.531144 I Listening on TCP [fe80::21d:60ff:fe40:5a17%eth0]:6544 |
---|
109 | 2012-12-02 06:31:37.454647 I Main::Registering HttpStatus Extension |
---|
110 | 2012-12-02 06:31:37.456288 I Listening on TCP 127.0.0.1:6543 |
---|
111 | 2012-12-02 06:31:37.456349 I Listening on TCP 192.168.2.4:6543 |
---|
112 | 2012-12-02 06:31:37.458908 I Listening on TCP [::1]:6543 |
---|
113 | 2012-12-02 06:31:37.459003 I Listening on TCP [fe80::21d:60ff:fe40:5a17%eth0]:6543 |
---|
114 | 2012-12-02 06:31:37.460670 N AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min |
---|
115 | 2012-12-02 06:31:39.529997 I Reschedule requested for MATCH 0 0 0 - SchedulerInit |
---|
116 | 2012-12-02 06:31:39.597666 I Scheduled 28 items in 0.0 = 0.01 match + 0.03 check + 0.01 place |
---|
117 | 2012-12-02 06:31:39.611258 I Scheduler: Seem to be woken up by USER |
---|
118 | 2012-12-02 06:31:46.526501 I Running housekeeping thread |
---|
119 | 2012-12-02 06:31:48.365671 I MainServer::ANN Monitor |
---|
120 | 2012-12-02 06:31:48.365683 I adding: zeus as a client (events: 0) |
---|
121 | 2012-12-02 06:31:48.365980 I MainServer::ANN Monitor |
---|
122 | 2012-12-02 06:31:48.365987 I adding: zeus as a client (events: 1) |
---|
123 | 2012-12-02 06:31:48.383824 I Getting next free recorder after : -1 |
---|
124 | 2012-12-02 06:31:48.383841 I Checking card 5. Best card so far -1 |
---|
125 | 2012-12-02 06:31:48.384282 I Checking card 6. Best card so far 5 |
---|
126 | 2012-12-02 06:31:48.384687 I Checking card 7. Best card so far 6 |
---|
127 | 2012-12-02 06:31:48.385436 I Checking card 8. Best card so far 6 |
---|
128 | 2012-12-02 06:31:48.386159 I Best card is 6 |
---|
129 | 2012-12-02 06:31:48.388281 I MainServer::ANN Playback |
---|
130 | 2012-12-02 06:31:48.388289 I adding: zeus as a client (events: 0) |
---|
131 | 2012-12-02 06:31:48.394664 I TVRec(6): Found channel (63) on current card(6). |
---|
132 | 2012-12-02 06:31:48.395603 I TVRec(6): Changing from None to WatchingLiveTV |
---|
133 | 2012-12-02 06:31:48.395622 I TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, |
---|
134 | 2012-12-02 06:31:48.395639 I TVRec(6): HandleTuning Request: Program(NULL) channel() input() flags(LiveTV,) |
---|
135 | 2012-12-02 06:31:48.395663 I ChannelBase(6): Looking for startchannel '63' on input 'tuner' |
---|
136 | 2012-12-02 06:31:48.397917 I ChannelBase(6): Found startchannel '63' on input 'tuner' |
---|
137 | 2012-12-02 06:31:48.397993 I TVRec(6): HW Tuner: 6->6 |
---|
138 | 2012-12-02 06:31:48.398005 I TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, |
---|
139 | 2012-12-02 06:31:48.398019 I TVRec(6): No recorder yet, calling TuningFrequency |
---|
140 | 2012-12-02 06:31:48.398304 I V4LChannel(/dev/video2): Device name 'Hauppauge WinTV-HVR2250' driver 'saa7164'. |
---|
141 | 2012-12-02 06:31:48.398322 I V4LChannel(/dev/video2): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 0 |
---|
142 | 2012-12-02 06:31:48.406148 I ChannelBase(6): Input #6: 'tuner' schan(63) sourceid(1) ccid(6) |
---|
143 | 2012-12-02 06:31:48.406160 I ChannelBase(6): Current Input #6: 'tuner' |
---|
144 | 2012-12-02 06:31:48.406187 I Global TVFormat Setting 'NTSC' |
---|
145 | 2012-12-02 06:31:48.406217 I V4LChannel(/dev/video2): Input #6: 'tuner' schan(63) tun() v4l1(Unknown) v4l2(NTSC) |
---|
146 | 2012-12-02 06:31:48.406230 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(6) |
---|
147 | 2012-12-02 06:31:48.406240 I Channel(/dev/video2)::SwitchToInput(in 6, '') |
---|
148 | 2012-12-02 06:31:48.428505 I V4LChannel(/dev/video2): SetInputAndFormat(6, NTSC) (v4l v2) input_switch: 0 mode_switch: 1 |
---|
149 | 2012-12-02 06:31:48.430753 I DTVChan(/dev/video2): SetChannelByString(63): |
---|
150 | 2012-12-02 06:31:48.432326 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(6) |
---|
151 | 2012-12-02 06:31:48.432344 I Channel(/dev/video2)::Tune(63): curList[61].freq(457250) |
---|
152 | 2012-12-02 06:31:48.432353 I V4LChannel(/dev/video2): Tune(457250000, ) |
---|
153 | 2012-12-02 06:31:48.727691 I Channel(/dev/video2)::Tune(): Frequency is now 457250000 |
---|
154 | 2012-12-02 06:31:48.727700 I DTVChan(/dev/video2): SetChannelByString(63): success |
---|
155 | 2012-12-02 06:31:48.733022 I TVRec(6): CreateLiveTVRingBuffer(63) |
---|
156 | 2012-12-02 06:31:48.733405 I TVRec(6): GetProgramRingBufferForLiveTV() |
---|
157 | 2012-12-02 06:31:48.736145 N AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min |
---|
158 | 2012-12-02 06:31:48.751562 I TVRec(6): StartedRecording(1063_2012-12-02T12:31:48Z) fn(/home/mythtv/rec/1063_20121202123148.mpg) |
---|
159 | 2012-12-02 06:31:48.753189 I Chain: Appended@0 '1063_20121202123148' |
---|
160 | 2012-12-02 06:31:48.754017 I TVRec(6): Starting Signal Monitor |
---|
161 | 2012-12-02 06:31:48.754033 I TVRec(6): SetupSignalMonitor(1, 1) |
---|
162 | 2012-12-02 06:31:48.754085 I TVRec(6): Signal monitor successfully created |
---|
163 | 2012-12-02 06:31:48.754264 I TVRec(6): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, |
---|
164 | 2012-12-02 06:31:48.754281 I TVRec(6): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, |
---|
165 | 2012-12-02 06:31:48.754294 I TVRec(6): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
---|
166 | 2012-12-02 06:31:48.754307 I TVRec(6): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning, |
---|
167 | 2012-12-02 06:31:48.754313 I DummyDTVRecorder -- started |
---|
168 | 2012-12-02 06:31:48.754325 I TVRec(6): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
169 | 2012-12-02 06:31:48.754337 I TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
170 | 2012-12-02 06:31:48.754350 I TVRec(6): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
171 | 2012-12-02 06:31:48.754365 I TVRec(6): Got good signal |
---|
172 | 2012-12-02 06:31:48.754415 I TVRec(6): TeardownSignalMonitor() -- begin |
---|
173 | 2012-12-02 06:31:48.804574 I TVRec(6): TeardownSignalMonitor() -- end |
---|
174 | 2012-12-02 06:31:48.804589 I TVRec(6): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, |
---|
175 | 2012-12-02 06:31:48.804603 I TVRec(6): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, |
---|
176 | 2012-12-02 06:31:48.804640 I TVRec(6): Starting Recorder |
---|
177 | 2012-12-02 06:31:48.805021 I TVRec(6): FinishedRecording(1063_2012-12-02T12:31:48Z) |
---|
178 | title: Brigadoon |
---|
179 | in recgroup: LiveTV status: Recording:Recorded is_dummy finished_now |
---|
180 | 2012-12-02 06:31:48.805303 I Chain: Updated endtime for '1063_20121202123148' to 20121202123148 |
---|
181 | 2012-12-02 06:31:48.805799 I TVRec(6): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, |
---|
182 | 2012-12-02 06:31:48.818378 I Using profile 'Live TV' to record |
---|
183 | 2012-12-02 06:31:48.818415 I TVRec(6): SwitchLiveTVRingBuffer(discont 1, set_next_rec 0) curRec(1063_2012-12-02T12:31:48Z) curRec.size(0) |
---|
184 | 2012-12-02 06:31:48.818828 I TVRec(6): GetProgramRingBufferForLiveTV() |
---|
185 | 2012-12-02 06:31:48.821111 N AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min |
---|
186 | 2012-12-02 06:31:48.827048 E RecordingInfo::InsertProgram(ProgramInfo(1063_20121202123148.mpg): channame(Turner Classic Movies) startts(Sun Dec 2 11:00:00 2012) endts(Sun Dec 2 13:00:00 2012) |
---|
187 | recstartts(Sun Dec 2 12:31:48 2012) recendts(Sun Dec 2 13:00:00 2012) |
---|
188 | title(Brigadoon)): recording already exists... |
---|
189 | 2012-12-02 06:31:48.829714 I TVRec(6): StartedRecording(1063_2012-12-02T12:31:49Z) fn(/home/mythtv/rec/1063_20121202123149.mpg) |
---|
190 | 2012-12-02 06:31:48.831925 I Chain: Appended@1 '1063_20121202123149' |
---|
191 | 2012-12-02 06:31:48.832230 I DTVRec(6): ResetForNewFile(void) |
---|
192 | 2012-12-02 06:31:48.833034 I RecBase(6:/dev/video2): SetRingBuffer(0x7fa338031e60) '/home/mythtv/rec/1063_20121202123149.mpg' |
---|
193 | 2012-12-02 06:31:48.833054 I RecBase(6:/dev/video2): SetRecording(0x7fa338040450) title(Brigadoon) |
---|
194 | 2012-12-02 06:31:48.838857 I TVRec(6): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, |
---|
195 | 2012-12-02 06:31:48.838889 I DTVRec(6): ResetForNewFile(void) |
---|
196 | 2012-12-02 06:31:48.839808 I V4LRec(6:/dev/video2): vbi_format rate: 27000000 |
---|
197 | offset: 0 |
---|
198 | samples_per_line: 1440 |
---|
199 | starts: 10, 274 |
---|
200 | counts: 18, 18 |
---|
201 | flags: 0x0 |
---|
202 | 2012-12-02 06:31:48.850916 I DevRdB(/dev/video2): buffer size 9400 KB |
---|
203 | 2012-12-02 06:31:48.850942 I MPEGRec(/dev/video2): DRB ready |
---|
204 | 2012-12-02 06:31:48.851046 I MPEGRec(/dev/video2): Initial startup of recorder |
---|
205 | 2012-12-02 06:31:48.851058 I MPEGRec(/dev/video2): StartEncoding |
---|
206 | 2012-12-02 06:31:48.851099 I DevRdB(/dev/video2): Start() -- begin |
---|
207 | 2012-12-02 06:31:48.851178 I DevRdB(/dev/video2): Start() -- middle |
---|
208 | 2012-12-02 06:31:48.851185 I DevRdB(/dev/video2): Start() -- end |
---|
209 | 2012-12-02 06:31:48.854047 I TVRec(6): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, |
---|
210 | 2012-12-02 06:31:48.854066 I TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, |
---|
211 | 2012-12-02 06:31:49.493052 I DTVRec(6): FindPSKeyFrames: frame rate = 29970 |
---|
212 | 2012-12-02 06:31:49.836213 I TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, |
---|
213 | 2012-12-02 06:31:54.756586 I TVRec(6): StopLiveTV(void) curRec: 0x7fa338040450 pseudoRec: 0x0 |
---|
214 | 2012-12-02 06:31:54.757126 I TVRec(6): Changing from WatchingLiveTV to None |
---|
215 | 2012-12-02 06:31:54.757153 I TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
---|
216 | 2012-12-02 06:31:54.757170 I TVRec(6): HandleTuning Request: Program(NULL) channel() input() flags(KillRec,KillRingBuffer,) |
---|
217 | 2012-12-02 06:31:55.003889 I MPEGRec(/dev/video2): run finishing up |
---|
218 | 2012-12-02 06:31:55.003899 I MPEGRec(/dev/video2): StopEncoding |
---|
219 | 2012-12-02 06:31:55.023984 I DevRdB(/dev/video2): Stop() -- begin |
---|
220 | 2012-12-02 06:31:55.024218 I DevRdB(/dev/video2): Stop() -- end |
---|
221 | 2012-12-02 06:31:55.311559 I TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
---|
222 | 2012-12-02 06:31:55.311664 I RecordingQuality() start(2012-12-02T11:00:00Z) end(2012-12-02T13:00:00Z) score(1) |
---|
223 | 2012-12-02 06:31:55.311727 I RecBase(6:/dev/video2): SetRecording(0x0) |
---|
224 | 2012-12-02 06:31:55.312765 I TVRec(6): FinishedRecording(1063_2012-12-02T12:31:49Z) good recq:<RecordingQuality overall_score="1" key="1063_2012-12-02T12:31:49Z" /> |
---|
225 | |
---|
226 | 2012-12-02 06:31:55.312826 I TVRec(6): FinishedRecording(1063_2012-12-02T12:31:49Z) |
---|
227 | title: Brigadoon |
---|
228 | in recgroup: LiveTV status: Recorder Failed:Recorder Failed not_dummy finished_now |
---|
229 | 2012-12-02 06:31:55.313103 I Chain: Updated endtime for '1063_20121202123149' to 20121202123155 |
---|
230 | 2012-12-02 06:31:55.313736 I SaveVideoProperties(0x38, 0x20) |
---|
231 | 2012-12-02 06:31:55.315045 I TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
---|
232 | 2012-12-02 06:31:55.315057 I TVRec(6): Tearing down RingBuffer |
---|
233 | 2012-12-02 06:31:55.315422 I TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, |
---|
234 | 2012-12-02 06:31:55.315771 I TVRec(6): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,RingBufferReady, |
---|
235 | 2012-12-02 06:31:55.319013 I Getting next free recorder after : -1 |
---|
236 | 2012-12-02 06:31:55.319023 I Checking card 5. Best card so far -1 |
---|
237 | 2012-12-02 06:31:55.319557 I Checking card 6. Best card so far 5 |
---|
238 | 2012-12-02 06:31:55.320007 I Checking card 7. Best card so far 6 |
---|
239 | 2012-12-02 06:31:55.320712 I Checking card 8. Best card so far 6 |
---|
240 | 2012-12-02 06:31:55.321402 I Best card is 6 |
---|
241 | 2012-12-02 06:31:55.323617 I MainServer::ANN Playback |
---|
242 | 2012-12-02 06:31:55.323625 I adding: zeus as a client (events: 0) |
---|
243 | 2012-12-02 06:31:55.329488 I TVRec(6): Found channel (63) on current card(6). |
---|
244 | 2012-12-02 06:31:55.340415 I TVRec(6): Changing from None to WatchingLiveTV |
---|
245 | 2012-12-02 06:31:55.340434 I TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady, |
---|
246 | 2012-12-02 06:31:55.340448 I TVRec(6): HandleTuning Request: Program(NULL) channel() input() flags(LiveTV,) |
---|
247 | 2012-12-02 06:31:55.340461 I ChannelBase(6): Looking for startchannel '63' on input 'tuner' |
---|
248 | 2012-12-02 06:31:55.342228 I ChannelBase(6): Found startchannel '63' on input 'tuner' |
---|
249 | 2012-12-02 06:31:55.342262 I TVRec(6): HW Tuner: 6->6 |
---|
250 | 2012-12-02 06:31:55.342273 I TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, |
---|
251 | 2012-12-02 06:31:55.342281 I TVRec(6): No recorder yet, calling TuningFrequency |
---|
252 | 2012-12-02 06:31:55.342551 I V4LChannel(/dev/video2): Device name 'Hauppauge WinTV-HVR2250' driver 'saa7164'. |
---|
253 | 2012-12-02 06:31:55.342568 I V4LChannel(/dev/video2): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 0 |
---|
254 | 2012-12-02 06:31:55.350423 I ChannelBase(6): Input #6: 'tuner' schan(63) sourceid(1) ccid(6) |
---|
255 | 2012-12-02 06:31:55.350434 I ChannelBase(6): Current Input #6: 'tuner' |
---|
256 | 2012-12-02 06:31:55.350461 I Global TVFormat Setting 'NTSC' |
---|
257 | 2012-12-02 06:31:55.350491 I V4LChannel(/dev/video2): Input #6: 'tuner' schan(63) tun() v4l1(Unknown) v4l2(NTSC) |
---|
258 | 2012-12-02 06:31:55.350503 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(6) |
---|
259 | 2012-12-02 06:31:55.350513 I Channel(/dev/video2)::SwitchToInput(in 6, '') |
---|
260 | 2012-12-02 06:31:55.352380 I V4LChannel(/dev/video2): SetInputAndFormat(6, NTSC) (v4l v2) input_switch: 0 mode_switch: 1 |
---|
261 | 2012-12-02 06:31:55.355112 I DTVChan(/dev/video2): SetChannelByString(63): |
---|
262 | 2012-12-02 06:31:55.356593 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(6) |
---|
263 | 2012-12-02 06:31:55.356609 I Channel(/dev/video2)::Tune(63): curList[61].freq(457250) |
---|
264 | 2012-12-02 06:31:55.356618 I V4LChannel(/dev/video2): Tune(457250000, ) |
---|
265 | 2012-12-02 06:31:55.647919 I Channel(/dev/video2)::Tune(): Frequency is now 457250000 |
---|
266 | 2012-12-02 06:31:55.647927 I DTVChan(/dev/video2): SetChannelByString(63): success |
---|
267 | 2012-12-02 06:31:55.654060 I TVRec(6): CreateLiveTVRingBuffer(63) |
---|
268 | 2012-12-02 06:31:55.654434 I TVRec(6): GetProgramRingBufferForLiveTV() |
---|
269 | 2012-12-02 06:31:55.656848 N AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min |
---|
270 | 2012-12-02 06:31:55.665076 I TVRec(6): StartedRecording(1063_2012-12-02T12:31:55Z) fn(/home/mythtv/rec/1063_20121202123155.mpg) |
---|
271 | 2012-12-02 06:31:55.666451 I Chain: Appended@0 '1063_20121202123155' |
---|
272 | 2012-12-02 06:31:55.667332 I TVRec(6): Starting Signal Monitor |
---|
273 | 2012-12-02 06:31:55.667342 I TVRec(6): SetupSignalMonitor(1, 1) |
---|
274 | 2012-12-02 06:31:55.667369 I TVRec(6): Signal monitor successfully created |
---|
275 | 2012-12-02 06:31:55.667474 I TVRec(6): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, |
---|
276 | 2012-12-02 06:31:55.667489 I TVRec(6): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady, |
---|
277 | 2012-12-02 06:31:55.667502 I TVRec(6): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, |
---|
278 | 2012-12-02 06:31:55.667515 I TVRec(6): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
279 | 2012-12-02 06:31:55.667520 I DummyDTVRecorder -- started |
---|
280 | 2012-12-02 06:31:55.667532 I TVRec(6): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
281 | 2012-12-02 06:31:55.667545 I TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
282 | 2012-12-02 06:31:55.667558 I TVRec(6): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, |
---|
283 | 2012-12-02 06:31:55.667567 I TVRec(6): Got good signal |
---|
284 | 2012-12-02 06:31:55.667614 I TVRec(6): TeardownSignalMonitor() -- begin |
---|
285 | 2012-12-02 06:31:55.717661 I TVRec(6): TeardownSignalMonitor() -- end |
---|
286 | 2012-12-02 06:31:55.717676 I TVRec(6): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, |
---|
287 | 2012-12-02 06:31:55.717689 I TVRec(6): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, |
---|
288 | 2012-12-02 06:31:55.717714 I TVRec(6): Starting Recorder |
---|
289 | 2012-12-02 06:31:55.718061 I TVRec(6): FinishedRecording(1063_2012-12-02T12:31:55Z) |
---|
290 | title: Brigadoon |
---|
291 | in recgroup: LiveTV status: Recording:Recorded is_dummy finished_now |
---|
292 | 2012-12-02 06:31:55.718332 I Chain: Updated endtime for '1063_20121202123155' to 20121202123155 |
---|
293 | 2012-12-02 06:31:55.718774 I TVRec(6): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, |
---|
294 | 2012-12-02 06:31:55.731784 I Using profile 'Live TV' to record |
---|
295 | 2012-12-02 06:31:55.731813 I TVRec(6): SwitchLiveTVRingBuffer(discont 1, set_next_rec 0) curRec(1063_2012-12-02T12:31:55Z) curRec.size(0) |
---|
296 | 2012-12-02 06:31:55.732218 I TVRec(6): GetProgramRingBufferForLiveTV() |
---|
297 | 2012-12-02 06:31:55.734492 N AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min |
---|
298 | 2012-12-02 06:31:55.740288 E RecordingInfo::InsertProgram(ProgramInfo(1063_20121202123155.mpg): channame(Turner Classic Movies) startts(Sun Dec 2 11:00:00 2012) endts(Sun Dec 2 13:00:00 2012) |
---|
299 | recstartts(Sun Dec 2 12:31:55 2012) recendts(Sun Dec 2 13:00:00 2012) |
---|
300 | title(Brigadoon)): recording already exists... |
---|
301 | 2012-12-02 06:31:55.743020 I TVRec(6): StartedRecording(1063_2012-12-02T12:31:56Z) fn(/home/mythtv/rec/1063_20121202123156.mpg) |
---|
302 | 2012-12-02 06:31:55.745181 I Chain: Appended@1 '1063_20121202123156' |
---|
303 | 2012-12-02 06:31:55.745408 I DTVRec(6): ResetForNewFile(void) |
---|
304 | 2012-12-02 06:31:55.745524 I RecBase(6:/dev/video2): SetRingBuffer(0x7fa338019660) '/home/mythtv/rec/1063_20121202123156.mpg' |
---|
305 | 2012-12-02 06:31:55.745538 I RecBase(6:/dev/video2): SetRecording(0x7fa3380308a0) title(Brigadoon) |
---|
306 | 2012-12-02 06:31:55.751984 I TVRec(6): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, |
---|
307 | 2012-12-02 06:31:55.752020 I DTVRec(6): ResetForNewFile(void) |
---|
308 | 2012-12-02 06:31:55.752906 I V4LRec(6:/dev/video2): vbi_format rate: 27000000 |
---|
309 | offset: 0 |
---|
310 | samples_per_line: 1440 |
---|
311 | starts: 10, 274 |
---|
312 | counts: 18, 18 |
---|
313 | flags: 0x0 |
---|
314 | 2012-12-02 06:31:55.763008 I DevRdB(/dev/video2): buffer size 9400 KB |
---|
315 | 2012-12-02 06:31:55.763023 I MPEGRec(/dev/video2): DRB ready |
---|
316 | 2012-12-02 06:31:55.763092 I MPEGRec(/dev/video2): Initial startup of recorder |
---|
317 | 2012-12-02 06:31:55.763099 I MPEGRec(/dev/video2): StartEncoding |
---|
318 | 2012-12-02 06:31:55.763117 I DevRdB(/dev/video2): Start() -- begin |
---|
319 | 2012-12-02 06:31:55.763187 I DevRdB(/dev/video2): Start() -- middle |
---|
320 | 2012-12-02 06:31:55.763195 I DevRdB(/dev/video2): Start() -- end |
---|
321 | 2012-12-02 06:31:55.767133 I TVRec(6): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, |
---|
322 | 2012-12-02 06:31:55.767150 I TVRec(6): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, |
---|
323 | 2012-12-02 06:31:56.420091 I DTVRec(6): FindPSKeyFrames: frame rate = 29970 |
---|
324 | 2012-12-02 06:31:56.430448 I TVRec(6): StopLiveTV(void) curRec: 0x7fa3380308a0 pseudoRec: 0x0 |
---|
325 | 2012-12-02 06:31:56.430798 I TVRec(6): Changing from WatchingLiveTV to None |
---|
326 | 2012-12-02 06:31:56.430818 I TVRec(6): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
---|
327 | 2012-12-02 06:31:56.430834 I TVRec(6): HandleTuning Request: Program(NULL) channel() input() flags(KillRec,KillRingBuffer,) |
---|
328 | 2012-12-02 06:31:56.924254 I MPEGRec(/dev/video2): run finishing up |
---|
329 | 2012-12-02 06:31:56.924267 I MPEGRec(/dev/video2): StopEncoding |
---|
330 | 2012-12-02 06:31:56.944355 I DevRdB(/dev/video2): Stop() -- begin |
---|
331 | 2012-12-02 06:31:56.944586 I DevRdB(/dev/video2): Stop() -- end |
---|
332 | 2012-12-02 06:31:57.228324 I TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
---|
333 | 2012-12-02 06:31:57.228414 I RecordingQuality() start(2012-12-02T11:00:00Z) end(2012-12-02T13:00:00Z) score(1) |
---|
334 | 2012-12-02 06:31:57.228474 I RecBase(6:/dev/video2): SetRecording(0x0) |
---|
335 | 2012-12-02 06:31:57.229021 I TVRec(6): FinishedRecording(1063_2012-12-02T12:31:56Z) good recq:<RecordingQuality overall_score="1" key="1063_2012-12-02T12:31:56Z" /> |
---|
336 | |
---|
337 | 2012-12-02 06:31:57.229074 I TVRec(6): FinishedRecording(1063_2012-12-02T12:31:56Z) |
---|
338 | title: Brigadoon |
---|
339 | in recgroup: LiveTV status: Recorder Failed:Recorder Failed not_dummy finished_now |
---|
340 | 2012-12-02 06:31:57.229324 I Chain: Updated endtime for '1063_20121202123156' to 20121202123157 |
---|
341 | 2012-12-02 06:31:57.229957 I SaveVideoProperties(0x38, 0x20) |
---|
342 | 2012-12-02 06:31:57.231260 I TVRec(6): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
---|
343 | 2012-12-02 06:31:57.231271 I TVRec(6): Tearing down RingBuffer |
---|
344 | 2012-12-02 06:31:57.231442 I TVRec(6): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, |
---|
345 | 2012-12-02 06:32:13.996888 C Received Interrupt: Code 128, PID 0, UID 0, Value 0x00000000 |
---|
346 | 2012-12-02 06:32:13.997772 N MythBackend exiting |
---|
347 | 2012-12-02 06:32:15.647060 I TVRec(5): ClearFlags(RunMainLoop,) -> 0x0 |
---|
348 | 2012-12-02 06:32:15.647286 I TVRec(5): ClearFlags(RecorderRunning,) -> 0x0 |
---|
349 | 2012-12-02 06:32:15.647310 I TVRec(6): ClearFlags(RunMainLoop,) -> RingBufferReady, |
---|
350 | 2012-12-02 06:32:15.647644 I TVRec(6): ClearFlags(RecorderRunning,) -> RingBufferReady, |
---|
351 | 2012-12-02 06:32:15.647678 I TVRec(7): ClearFlags(RunMainLoop,) -> 0x0 |
---|
352 | 2012-12-02 06:32:15.647771 I DVBChan(7:/dev/dvb/adapter0/frontend0): Closing DVB channel |
---|
353 | 2012-12-02 06:32:15.647806 I TVRec(7): ClearFlags(RecorderRunning,) -> 0x0 |
---|
354 | 2012-12-02 06:32:15.647823 I TVRec(8): ClearFlags(RunMainLoop,) -> 0x0 |
---|
355 | 2012-12-02 06:32:15.647876 I DVBChan(8:/dev/dvb/adapter0/frontend0): Closing DVB channel |
---|
356 | 2012-12-02 06:32:15.647908 I TVRec(8): ClearFlags(RecorderRunning,) -> 0x0 |
---|
357 | 2012-12-02 06:32:15.647932 I Waiting for threads to exit. |
---|