| 1 | 2012-05-18 08:10:48.318824 C mythbackend version: fixes/0.25 [v0.25-94-gec51a97] www.mythtv.org |
|---|
| 2 | 2012-05-18 08:10:48.318843 C Qt version: compile: 4.8.1, runtime: 4.8.1 |
|---|
| 3 | 2012-05-18 08:10:48.318845 N Enabled verbose msgs: general record channel |
|---|
| 4 | 2012-05-18 08:10:48.318863 N Setting Log Level to LOG_INFO |
|---|
| 5 | 2012-05-18 08:10:48.318904 I Added logging to the console |
|---|
| 6 | 2012-05-18 08:10:48.318908 I Added database logging to table logging |
|---|
| 7 | 2012-05-18 08:10:48.318988 N Setting up SIGHUP handler |
|---|
| 8 | 2012-05-18 08:10:48.319051 N Using runtime prefix = /usr |
|---|
| 9 | 2012-05-18 08:10:48.319062 N Using configuration directory = /root/.mythtv |
|---|
| 10 | 2012-05-18 08:10:48.319266 I Assumed character encoding: en_CA.UTF-8 |
|---|
| 11 | 2012-05-18 08:10:48.319607 N Empty LocalHostName. |
|---|
| 12 | 2012-05-18 08:10:48.319612 I Using localhost value of mythserver |
|---|
| 13 | 2012-05-18 08:10:48.345722 N Setting QT default locale to EN_CA |
|---|
| 14 | 2012-05-18 08:10:48.345866 I Current locale EN_CA |
|---|
| 15 | 2012-05-18 08:10:48.345960 N Reading locale defaults from /usr/share/mythtv//locales/en_ca.xml |
|---|
| 16 | 2012-05-18 08:10:48.376216 I Current MythTV Schema Version (DBSchemaVer): 1299 |
|---|
| 17 | 2012-05-18 08:10:48.377463 I Loading en_us translation for module mythfrontend |
|---|
| 18 | 2012-05-18 08:10:48.379670 N MythBackend: Starting up as the master server. |
|---|
| 19 | 2012-05-18 08:10:48.392055 I TVRec(1): Start channel: 2. |
|---|
| 20 | 2012-05-18 08:10:48.392622 I V4LChannel(/dev/video0): Device name 'Hauppauge WinTV PVR-250' driver 'ivtv'. |
|---|
| 21 | 2012-05-18 08:10:48.392661 I V4LChannel(/dev/video0): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 1 |
|---|
| 22 | 2012-05-18 08:10:48.411875 I ChannelBase(1): Input #1: 'Tuner 1' schan(2) sourceid(1) ccid(1) |
|---|
| 23 | 2012-05-18 08:10:48.411886 I ChannelBase(1): Current Input #1: 'Tuner 1' |
|---|
| 24 | 2012-05-18 08:10:48.413552 I Global TVFormat Setting 'NTSC' |
|---|
| 25 | 2012-05-18 08:10:48.413594 I V4LChannel(/dev/video0): Input #1: 'Tuner 1' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
|---|
| 26 | 2012-05-18 08:10:48.413603 I V4LChannel(/dev/video0): SetFormat(Default) fmt(NTSC) input(1) |
|---|
| 27 | 2012-05-18 08:10:48.413614 I V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 28 | 2012-05-18 08:10:48.414429 I V4LChannel(/dev/video0): SetFormat(NTSC) fmt(NTSC) input(1) |
|---|
| 29 | 2012-05-18 08:10:48.416133 I Channel(/dev/video0)::SwitchToInput(in 1, '') |
|---|
| 30 | 2012-05-18 08:10:48.416995 I V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 31 | 2012-05-18 08:10:48.417012 I DTVChan(/dev/video0): SetChannelByString(2): |
|---|
| 32 | 2012-05-18 08:10:48.418750 I V4LChannel(/dev/video0): SetFormat(Default) fmt(NTSC) input(1) |
|---|
| 33 | 2012-05-18 08:10:48.418768 I Channel(/dev/video0)::Tune(2): curList[0].freq(55250) |
|---|
| 34 | 2012-05-18 08:10:48.418773 I V4LChannel(/dev/video0): Tune(55250000, ) |
|---|
| 35 | 2012-05-18 08:10:48.424544 I Channel(/dev/video0)::Tune(): Frequency is now 55250000 |
|---|
| 36 | 2012-05-18 08:10:48.424565 I DTVChan(/dev/video0): SetChannelByString(2): success |
|---|
| 37 | 2012-05-18 08:10:48.439432 I TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop, |
|---|
| 38 | 2012-05-18 08:10:48.439448 I TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
|---|
| 39 | 2012-05-18 08:10:48.441382 I TVRec(2): Start channel: 2. |
|---|
| 40 | 2012-05-18 08:10:48.441681 I V4LChannel(/dev/video1): Device name 'WinTV PVR 500 (unit #1)' driver 'ivtv'. |
|---|
| 41 | 2012-05-18 08:10:48.441693 I V4LChannel(/dev/video1): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 1 |
|---|
| 42 | 2012-05-18 08:10:48.448788 I ChannelBase(2): Input #2: 'Tuner 1' schan(2) sourceid(1) ccid(2) |
|---|
| 43 | 2012-05-18 08:10:48.448801 I ChannelBase(2): Current Input #2: 'Tuner 1' |
|---|
| 44 | 2012-05-18 08:10:48.448832 I Global TVFormat Setting 'NTSC' |
|---|
| 45 | 2012-05-18 08:10:48.448856 I V4LChannel(/dev/video1): Input #2: 'Tuner 1' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
|---|
| 46 | 2012-05-18 08:10:48.448864 I V4LChannel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) |
|---|
| 47 | 2012-05-18 08:10:48.448875 I V4LChannel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 48 | 2012-05-18 08:10:48.449529 I V4LChannel(/dev/video1): SetFormat(NTSC) fmt(NTSC) input(2) |
|---|
| 49 | 2012-05-18 08:10:48.449545 I Channel(/dev/video1)::SwitchToInput(in 2, '') |
|---|
| 50 | 2012-05-18 08:10:48.450199 I V4LChannel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 51 | 2012-05-18 08:10:48.450211 I DTVChan(/dev/video1): SetChannelByString(2): |
|---|
| 52 | 2012-05-18 08:10:48.451534 I V4LChannel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) |
|---|
| 53 | 2012-05-18 08:10:48.451550 I Channel(/dev/video1)::Tune(2): curList[0].freq(55250) |
|---|
| 54 | 2012-05-18 08:10:48.451556 I V4LChannel(/dev/video1): Tune(55250000, ) |
|---|
| 55 | 2012-05-18 08:10:48.468358 I Channel(/dev/video1)::Tune(): Frequency is now 55250000 |
|---|
| 56 | 2012-05-18 08:10:48.468362 I DTVChan(/dev/video1): SetChannelByString(2): success |
|---|
| 57 | 2012-05-18 08:10:48.474011 I TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop, |
|---|
| 58 | 2012-05-18 08:10:48.474031 I TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
|---|
| 59 | 2012-05-18 08:10:48.476214 I TVRec(3): Start channel: 2. |
|---|
| 60 | 2012-05-18 08:10:48.476525 I V4LChannel(/dev/video2): Device name 'WinTV PVR 500 (unit #2)' driver 'ivtv'. |
|---|
| 61 | 2012-05-18 08:10:48.476537 I V4LChannel(/dev/video2): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 1 |
|---|
| 62 | 2012-05-18 08:10:48.483783 I ChannelBase(3): Input #3: 'Tuner 1' schan(2) sourceid(1) ccid(3) |
|---|
| 63 | 2012-05-18 08:10:48.483796 I ChannelBase(3): Current Input #3: 'Tuner 1' |
|---|
| 64 | 2012-05-18 08:10:48.483820 I Global TVFormat Setting 'NTSC' |
|---|
| 65 | 2012-05-18 08:10:48.483844 I V4LChannel(/dev/video2): Input #3: 'Tuner 1' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
|---|
| 66 | 2012-05-18 08:10:48.483852 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(3) |
|---|
| 67 | 2012-05-18 08:10:48.483863 I V4LChannel(/dev/video2): SetInputAndFormat(3, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 68 | 2012-05-18 08:10:48.484547 I V4LChannel(/dev/video2): SetFormat(NTSC) fmt(NTSC) input(3) |
|---|
| 69 | 2012-05-18 08:10:48.484563 I Channel(/dev/video2)::SwitchToInput(in 3, '') |
|---|
| 70 | 2012-05-18 08:10:48.485235 I V4LChannel(/dev/video2): SetInputAndFormat(3, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 71 | 2012-05-18 08:10:48.485248 I DTVChan(/dev/video2): SetChannelByString(2): |
|---|
| 72 | 2012-05-18 08:10:48.486682 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(3) |
|---|
| 73 | 2012-05-18 08:10:48.486698 I Channel(/dev/video2)::Tune(2): curList[0].freq(55250) |
|---|
| 74 | 2012-05-18 08:10:48.486704 I V4LChannel(/dev/video2): Tune(55250000, ) |
|---|
| 75 | 2012-05-18 08:10:48.502202 I Channel(/dev/video2)::Tune(): Frequency is now 55250000 |
|---|
| 76 | 2012-05-18 08:10:48.502207 I DTVChan(/dev/video2): SetChannelByString(2): success |
|---|
| 77 | 2012-05-18 08:10:48.509519 I TVRec(3): SetFlags(RunMainLoop,) -> RunMainLoop, |
|---|
| 78 | 2012-05-18 08:10:48.509559 I TVRec(3): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, |
|---|
| 79 | 2012-05-18 08:10:48.545748 I Found 1 distinct programid authorities |
|---|
| 80 | 2012-05-18 08:10:48.546338 I New static DB connectionSchedCon |
|---|
| 81 | 2012-05-18 08:10:48.555087 I Listening on TCP 127.0.0.1:6544 |
|---|
| 82 | 2012-05-18 08:10:48.555121 I Listening on TCP 10.0.0.1:6544 |
|---|
| 83 | 2012-05-18 08:10:49.565083 I Main::Registering HttpStatus Extension |
|---|
| 84 | 2012-05-18 08:10:49.570846 I Listening on TCP 127.0.0.1:6543 |
|---|
| 85 | 2012-05-18 08:10:49.570954 I Listening on TCP 10.0.0.1:6543 |
|---|
| 86 | 2012-05-18 08:10:49.576918 N AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min |
|---|
| 87 | 2012-05-18 08:10:50.260099 I MainServer::ANN Monitor |
|---|
| 88 | 2012-05-18 08:10:50.260123 I adding: mythfront as a client (events: 0) |
|---|
| 89 | 2012-05-18 08:10:50.261832 I MainServer::ANN Monitor |
|---|
| 90 | 2012-05-18 08:10:50.261851 I adding: mythfront as a client (events: 1) |
|---|
| 91 | 2012-05-18 08:10:50.361201 I Bonjour: Service registration complete: name 'Mythbackend on mythserver' type '_mythbackend-master._tcp.' domain: 'local.' |
|---|
| 92 | 2012-05-18 08:10:51.565077 I Reschedule requested for id -1. |
|---|
| 93 | 2012-05-18 08:10:54.393825 I Scheduled 1506 items in 2.8 = 0.09 match + 2.74 place |
|---|
| 94 | 2012-05-18 08:10:54.676072 I Scheduler: Seem to be woken up by USER |
|---|
| 95 | 2012-05-18 08:10:56.290844 I MainServer::ANN Monitor |
|---|
| 96 | 2012-05-18 08:10:56.290861 I adding: mythserver as a client (events: 2) |
|---|
| 97 | 2012-05-18 08:10:58.559644 I Running housekeeping thread |
|---|
| 98 | 2012-05-18 08:11:00.511733 I MainServer::ANN Monitor |
|---|
| 99 | 2012-05-18 08:11:00.511777 I adding: mythserver as a client (events: 2) |
|---|
| 100 | 2012-05-18 08:11:01.520869 I MainServer::ANN Monitor |
|---|
| 101 | 2012-05-18 08:11:01.520875 I adding: mythserver as a client (events: 2) |
|---|
| 102 | 2012-05-18 08:11:06.072552 I MainServer::ANN Monitor |
|---|
| 103 | 2012-05-18 08:11:06.072596 I adding: mythserver as a client (events: 2) |
|---|
| 104 | 2012-05-18 08:11:07.652486 I MainServer::ANN Monitor |
|---|
| 105 | 2012-05-18 08:11:07.652504 I adding: mythserver as a client (events: 2) |
|---|
| 106 | 2012-05-18 08:11:07.662938 I MainServer::ANN Monitor |
|---|
| 107 | 2012-05-18 08:11:07.662955 I adding: mythserver as a client (events: 2) |
|---|
| 108 | 2012-05-18 08:11:08.678352 I MainServer::ANN Monitor |
|---|
| 109 | 2012-05-18 08:11:08.678435 I adding: mythserver as a client (events: 2) |
|---|
| 110 | 2012-05-18 08:11:08.728599 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb.py -l en -M CBS This Morning |
|---|
| 111 | 2012-05-18 08:11:08.733206 I Starting IO manager (write) |
|---|
| 112 | 2012-05-18 08:11:08.733426 I Starting IO manager (read) |
|---|
| 113 | 2012-05-18 08:11:08.733629 I Starting process signal handler |
|---|
| 114 | 2012-05-18 08:11:08.733781 I Starting process manager |
|---|
| 115 | 2012-05-18 08:11:08.990713 I MainServer::ANN Monitor |
|---|
| 116 | 2012-05-18 08:11:08.990719 I adding: mythserver as a client (events: 2) |
|---|
| 117 | 2012-05-18 08:11:08.994101 I MainServer::ANN Monitor |
|---|
| 118 | 2012-05-18 08:11:08.994106 I adding: mythserver as a client (events: 2) |
|---|
| 119 | 2012-05-18 08:11:09.763580 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb.py -l en -M AB Twister |
|---|
| 120 | 2012-05-18 08:11:09.894209 I MainServer::ANN Monitor |
|---|
| 121 | 2012-05-18 08:11:09.894218 I adding: mythserver as a client (events: 2) |
|---|
| 122 | 2012-05-18 08:11:10.440409 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -M CBS This Morning |
|---|
| 123 | 2012-05-18 08:11:10.443020 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -M AB Twister |
|---|
| 124 | 2012-05-18 08:11:12.594845 I MainServer::ANN Monitor |
|---|
| 125 | 2012-05-18 08:11:12.594863 I adding: mythserver as a client (events: 2) |
|---|
| 126 | 2012-05-18 08:11:13.362429 I MainServer::ANN Monitor |
|---|
| 127 | 2012-05-18 08:11:13.362435 I adding: mythserver as a client (events: 2) |
|---|
| 128 | 2012-05-18 08:11:13.365930 I MainServer::ANN Monitor |
|---|
| 129 | 2012-05-18 08:11:13.365936 I adding: mythserver as a client (events: 2) |
|---|
| 130 | 2012-05-18 08:11:14.249215 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb.py -l en -M Paid Programming |
|---|
| 131 | 2012-05-18 08:11:14.653503 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -M Paid Programming |
|---|
| 132 | 2012-05-18 08:11:15.162319 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -D 248938 1 1 |
|---|
| 133 | 2012-05-18 08:11:16.377331 I Returning Metadata Results: Paid Programming 0 0 |
|---|
| 134 | 2012-05-18 08:11:18.103915 I MainServer::ANN Monitor |
|---|
| 135 | 2012-05-18 08:11:18.103966 I adding: mythserver as a client (events: 2) |
|---|
| 136 | 2012-05-18 08:11:18.108823 I MainServer::ANN Monitor |
|---|
| 137 | 2012-05-18 08:11:18.108851 I adding: mythserver as a client (events: 2) |
|---|
| 138 | 2012-05-18 08:11:19.069550 I MainServer::ANN Monitor |
|---|
| 139 | 2012-05-18 08:11:19.069571 I adding: mythserver as a client (events: 2) |
|---|
| 140 | 2012-05-18 08:11:19.070322 I Reschedule requested for id 410. |
|---|
| 141 | 2012-05-18 08:11:21.958577 I Scheduled 1507 items in 2.9 = 0.03 match + 2.85 place |
|---|
| 142 | 2012-05-18 08:11:22.227162 I TVRec(1): RecordPending on inputid 1 |
|---|
| 143 | 2012-05-18 08:11:22.228071 I TVRec(1): StartRecording("CBS This Morning") |
|---|
| 144 | 2012-05-18 08:11:22.229297 I TVRec(1): ASK_RECORDING 1 0 0 0 |
|---|
| 145 | 2012-05-18 08:11:22.344135 I TVRec(1): StartedRecording(1017_2012-05-18T08:11:00) fn(/myth/1017_20120518081100.mpg) |
|---|
| 146 | 2012-05-18 08:11:22.344255 I TVRec(1): ClearFlags(CancelNextRecording,) -> RunMainLoop, |
|---|
| 147 | 2012-05-18 08:11:22.344409 I TVRec(1): Changing from None to RecordingOnly |
|---|
| 148 | 2012-05-18 08:11:22.344492 I TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, |
|---|
| 149 | 2012-05-18 08:11:22.344663 I TVRec(1): HandleTuning Request: Program(ProgramInfo(1017_20120518081100.mpg): channame(WIVB) startts(Fri May 18 07:00:00 2012) endts(Fri May 18 09:00:00 2012) |
|---|
| 150 | recstartts(Fri May 18 08:11:00 2012) recendts(Fri May 18 09:00:00 2012) |
|---|
| 151 | title(CBS This Morning)) channel() input() flags(Recording,) |
|---|
| 152 | 2012-05-18 08:11:22.349943 I TVRec(1): HW Tuner: 1->1 |
|---|
| 153 | 2012-05-18 08:11:22.349972 I TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, |
|---|
| 154 | 2012-05-18 08:11:22.349989 I TVRec(1): No recorder yet, calling TuningFrequency |
|---|
| 155 | 2012-05-18 08:11:22.351657 I V4LChannel(/dev/video0): Device name 'Hauppauge WinTV PVR-250' driver 'ivtv'. |
|---|
| 156 | 2012-05-18 08:11:22.351696 I V4LChannel(/dev/video0): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 1 |
|---|
| 157 | 2012-05-18 08:11:22.371242 I ChannelBase(1): Input #1: 'Tuner 1' schan(2) sourceid(1) ccid(1) |
|---|
| 158 | 2012-05-18 08:11:22.371253 I ChannelBase(1): Current Input #1: 'Tuner 1' |
|---|
| 159 | 2012-05-18 08:11:22.371292 I Global TVFormat Setting 'NTSC' |
|---|
| 160 | 2012-05-18 08:11:22.371312 I V4LChannel(/dev/video0): Input #1: 'Tuner 1' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
|---|
| 161 | 2012-05-18 08:11:22.371319 I V4LChannel(/dev/video0): SetFormat(Default) fmt(NTSC) input(1) |
|---|
| 162 | 2012-05-18 08:11:22.371325 I Channel(/dev/video0)::SwitchToInput(in 1, '') |
|---|
| 163 | 2012-05-18 08:11:22.372912 I V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 164 | 2012-05-18 08:11:22.372923 I DTVChan(/dev/video0): SetChannelByString(17): |
|---|
| 165 | 2012-05-18 08:11:22.374061 I V4LChannel(/dev/video0): SetFormat(Default) fmt(NTSC) input(1) |
|---|
| 166 | 2012-05-18 08:11:22.374071 I Channel(/dev/video0)::Tune(17): curList[15].freq(139250) |
|---|
| 167 | 2012-05-18 08:11:22.374075 I V4LChannel(/dev/video0): Tune(139250000, ) |
|---|
| 168 | 2012-05-18 08:11:22.384752 I Channel(/dev/video0)::Tune(): Frequency is now 139250000 |
|---|
| 169 | 2012-05-18 08:11:22.384755 I DTVChan(/dev/video0): SetChannelByString(17): success |
|---|
| 170 | 2012-05-18 08:11:22.386384 I TVRec(1): Starting Signal Monitor |
|---|
| 171 | 2012-05-18 08:11:22.386397 I TVRec(1): SetupSignalMonitor(1, 0) |
|---|
| 172 | 2012-05-18 08:11:22.386464 I TVRec(1): Signal monitor successfully created |
|---|
| 173 | 2012-05-18 08:11:22.390304 I TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, |
|---|
| 174 | 2012-05-18 08:11:22.390312 I TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, |
|---|
| 175 | 2012-05-18 08:11:22.390317 I TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
|---|
| 176 | 2012-05-18 08:11:22.390322 I TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
|---|
| 177 | 2012-05-18 08:11:22.390327 I TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, |
|---|
| 178 | 2012-05-18 08:11:22.390335 I TVRec(1): Got good signal |
|---|
| 179 | 2012-05-18 08:11:22.390368 I TVRec(1): TeardownSignalMonitor() -- begin |
|---|
| 180 | 2012-05-18 08:11:22.437014 I TVRec(1): TeardownSignalMonitor() -- end |
|---|
| 181 | 2012-05-18 08:11:22.437059 I TVRec(1): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder, |
|---|
| 182 | 2012-05-18 08:11:22.437093 I TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder, |
|---|
| 183 | 2012-05-18 08:11:22.437213 I TVRec(1): Starting Recorder |
|---|
| 184 | 2012-05-18 08:11:22.516610 I Using profile 'Default' to record |
|---|
| 185 | 2012-05-18 08:11:22.516639 I TVRec(1): rec->GetPathname(): '/myth/1017_20120518081100.mpg' |
|---|
| 186 | 2012-05-18 08:11:22.517184 I DTVRec(1): ResetForNewFile(void) |
|---|
| 187 | 2012-05-18 08:11:22.520325 I RecBase(1:/dev/video0): SetRingBuffer(0x7f3f2801e9b0) '/myth/1017_20120518081100.mpg' |
|---|
| 188 | 2012-05-18 08:11:22.520369 I RecBase(1:/dev/video0): SetRecording(0x7f3f1088a2f0) title(CBS This Morning) |
|---|
| 189 | 2012-05-18 08:11:22.529764 I DTVRec(1): ResetForNewFile(void) |
|---|
| 190 | 2012-05-18 08:11:22.536154 N AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min |
|---|
| 191 | 2012-05-18 08:11:22.537580 I Started recording: "CBS This Morning": channel 1017 on cardid 1, sourceid 1 |
|---|
| 192 | 2012-05-18 08:11:22.543159 I MainServer::ANN Monitor |
|---|
| 193 | 2012-05-18 08:11:22.543166 I adding: mythserver as a client (events: 2) |
|---|
| 194 | 2012-05-18 08:11:22.547398 I DevRdB(/dev/video0): buffer size 9400 KB |
|---|
| 195 | 2012-05-18 08:11:22.547414 I MPEGRec(/dev/video0): DRB ready |
|---|
| 196 | 2012-05-18 08:11:22.547434 I MPEGRec(/dev/video0): Initial startup of recorder |
|---|
| 197 | 2012-05-18 08:11:22.547439 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 198 | 2012-05-18 08:11:22.547717 I MainServer::ANN Monitor |
|---|
| 199 | 2012-05-18 08:11:22.547723 I adding: mythserver as a client (events: 2) |
|---|
| 200 | 2012-05-18 08:11:22.558400 I TVRec(1): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, |
|---|
| 201 | 2012-05-18 08:11:22.663886 I MainServer::ANN Monitor |
|---|
| 202 | 2012-05-18 08:11:22.663892 I adding: mythserver as a client (events: 2) |
|---|
| 203 | 2012-05-18 08:11:22.668142 I MainServer::ANN Monitor |
|---|
| 204 | 2012-05-18 08:11:22.668148 I adding: mythserver as a client (events: 2) |
|---|
| 205 | 2012-05-18 08:11:22.757954 I TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
|---|
| 206 | 2012-05-18 08:11:22.882439 I MPEGRec(/dev/video0): Encoding started |
|---|
| 207 | 2012-05-18 08:11:22.882458 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 208 | 2012-05-18 08:11:22.882536 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 209 | 2012-05-18 08:11:22.882541 I DevRdB(/dev/video0): Start() -- end |
|---|
| 210 | 2012-05-18 08:11:23.252297 I MainServer::ANN Monitor |
|---|
| 211 | 2012-05-18 08:11:23.252315 I adding: mythserver as a client (events: 2) |
|---|
| 212 | 2012-05-18 08:11:23.253346 I Reschedule requested for id 411. |
|---|
| 213 | 2012-05-18 08:11:25.385249 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 214 | 2012-05-18 08:11:25.385271 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 215 | 2012-05-18 08:11:25.385376 E MPEGRec(/dev/video0): Device error detected |
|---|
| 216 | 2012-05-18 08:11:25.385380 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 217 | 2012-05-18 08:11:25.385384 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 218 | 2012-05-18 08:11:25.794450 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb.py -l en -M CBS This Morning |
|---|
| 219 | 2012-05-18 08:11:26.192365 I MainServer::ANN Monitor |
|---|
| 220 | 2012-05-18 08:11:26.192372 I adding: mythserver as a client (events: 0) |
|---|
| 221 | 2012-05-18 08:11:26.194848 I MainServer::ANN Monitor |
|---|
| 222 | 2012-05-18 08:11:26.194857 I adding: mythserver as a client (events: 1) |
|---|
| 223 | 2012-05-18 08:11:26.302749 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -M CBS This Morning |
|---|
| 224 | 2012-05-18 08:11:26.371491 I MainServer::ANN Monitor |
|---|
| 225 | 2012-05-18 08:11:26.371496 I adding: mythserver as a client (events: 2) |
|---|
| 226 | 2012-05-18 08:11:26.374521 I MainServer::ANN Monitor |
|---|
| 227 | 2012-05-18 08:11:26.374527 I adding: mythserver as a client (events: 2) |
|---|
| 228 | 2012-05-18 08:11:26.488252 I Scheduled 1508 items in 3.2 = 0.03 match + 3.18 place |
|---|
| 229 | 2012-05-18 08:11:26.490658 I TVRec(2): RecordPending on inputid 2 |
|---|
| 230 | 2012-05-18 08:11:26.490866 I TVRec(2): StartRecording("AB Twister") |
|---|
| 231 | 2012-05-18 08:11:26.491914 I TVRec(2): ASK_RECORDING 2 0 0 0 |
|---|
| 232 | 2012-05-18 08:11:26.504410 I TVRec(2): StartedRecording(1018_2012-05-18T08:11:00) fn(/myth/1018_20120518081100.mpg) |
|---|
| 233 | 2012-05-18 08:11:26.504458 I TVRec(2): ClearFlags(CancelNextRecording,) -> RunMainLoop, |
|---|
| 234 | 2012-05-18 08:11:26.504643 I TVRec(2): Changing from None to RecordingOnly |
|---|
| 235 | 2012-05-18 08:11:26.504652 I TVRec(2): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, |
|---|
| 236 | 2012-05-18 08:11:26.504700 I TVRec(2): HandleTuning Request: Program(ProgramInfo(1018_20120518081100.mpg): channame(Shop TV Canada) startts(Fri May 18 08:00:00 2012) endts(Fri May 18 08:30:00 2012) |
|---|
| 237 | recstartts(Fri May 18 08:11:00 2012) recendts(Fri May 18 08:30:00 2012) |
|---|
| 238 | title(AB Twister)) channel() input() flags(Recording,) |
|---|
| 239 | 2012-05-18 08:11:26.505991 I TVRec(2): HW Tuner: 2->2 |
|---|
| 240 | 2012-05-18 08:11:26.506001 I TVRec(2): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, |
|---|
| 241 | 2012-05-18 08:11:26.506006 I TVRec(2): No recorder yet, calling TuningFrequency |
|---|
| 242 | 2012-05-18 08:11:26.506436 I V4LChannel(/dev/video1): Device name 'WinTV PVR 500 (unit #1)' driver 'ivtv'. |
|---|
| 243 | 2012-05-18 08:11:26.506446 I V4LChannel(/dev/video1): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 1 |
|---|
| 244 | 2012-05-18 08:11:26.511550 I ChannelBase(2): Input #2: 'Tuner 1' schan(2) sourceid(1) ccid(2) |
|---|
| 245 | 2012-05-18 08:11:26.511558 I ChannelBase(2): Current Input #2: 'Tuner 1' |
|---|
| 246 | 2012-05-18 08:11:26.511600 I Global TVFormat Setting 'NTSC' |
|---|
| 247 | 2012-05-18 08:11:26.511619 I V4LChannel(/dev/video1): Input #2: 'Tuner 1' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
|---|
| 248 | 2012-05-18 08:11:26.511626 I V4LChannel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) |
|---|
| 249 | 2012-05-18 08:11:26.511631 I Channel(/dev/video1)::SwitchToInput(in 2, '') |
|---|
| 250 | 2012-05-18 08:11:26.512581 I V4LChannel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 251 | 2012-05-18 08:11:26.512587 I DTVChan(/dev/video1): SetChannelByString(18): |
|---|
| 252 | 2012-05-18 08:11:26.514616 I V4LChannel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) |
|---|
| 253 | 2012-05-18 08:11:26.514628 I Channel(/dev/video1)::Tune(18): curList[16].freq(145250) |
|---|
| 254 | 2012-05-18 08:11:26.514632 I V4LChannel(/dev/video1): Tune(145250000, ) |
|---|
| 255 | 2012-05-18 08:11:26.540238 I Channel(/dev/video1)::Tune(): Frequency is now 145250000 |
|---|
| 256 | 2012-05-18 08:11:26.540249 I DTVChan(/dev/video1): SetChannelByString(18): success |
|---|
| 257 | 2012-05-18 08:11:26.547690 I TVRec(2): Starting Signal Monitor |
|---|
| 258 | 2012-05-18 08:11:26.547698 I TVRec(2): SetupSignalMonitor(1, 0) |
|---|
| 259 | 2012-05-18 08:11:26.547721 I TVRec(2): Signal monitor successfully created |
|---|
| 260 | 2012-05-18 08:11:26.549804 I TVRec(2): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, |
|---|
| 261 | 2012-05-18 08:11:26.549813 I TVRec(2): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, |
|---|
| 262 | 2012-05-18 08:11:26.549818 I TVRec(2): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
|---|
| 263 | 2012-05-18 08:11:26.549824 I TVRec(2): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
|---|
| 264 | 2012-05-18 08:11:26.549829 I TVRec(2): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, |
|---|
| 265 | 2012-05-18 08:11:26.553834 N AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 15 min |
|---|
| 266 | 2012-05-18 08:11:26.554139 I Tuning recording: "AB Twister": channel 1018 on cardid 2, sourceid 1 |
|---|
| 267 | 2012-05-18 08:11:26.557341 I TVRec(2): Got good signal |
|---|
| 268 | 2012-05-18 08:11:26.557433 I Updating status for "AB Twister" on cardid 2 (Tuning => Recording) |
|---|
| 269 | 2012-05-18 08:11:26.558837 I TVRec(2): TeardownSignalMonitor() -- begin |
|---|
| 270 | 2012-05-18 08:11:26.612106 I TVRec(2): TeardownSignalMonitor() -- end |
|---|
| 271 | 2012-05-18 08:11:26.612131 I TVRec(2): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder, |
|---|
| 272 | 2012-05-18 08:11:26.612139 I TVRec(2): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder, |
|---|
| 273 | 2012-05-18 08:11:26.612177 I TVRec(2): Starting Recorder |
|---|
| 274 | 2012-05-18 08:11:26.630493 I Using profile 'Default' to record |
|---|
| 275 | 2012-05-18 08:11:26.630503 I TVRec(2): rec->GetPathname(): '/myth/1018_20120518081100.mpg' |
|---|
| 276 | 2012-05-18 08:11:26.630664 I DTVRec(2): ResetForNewFile(void) |
|---|
| 277 | 2012-05-18 08:11:26.630741 I RecBase(2:/dev/video1): SetRingBuffer(0x7f3f1c04d760) '/myth/1018_20120518081100.mpg' |
|---|
| 278 | 2012-05-18 08:11:26.630750 I RecBase(2:/dev/video1): SetRecording(0x7f3f10446e10) title(AB Twister) |
|---|
| 279 | 2012-05-18 08:11:26.636747 I DTVRec(2): ResetForNewFile(void) |
|---|
| 280 | 2012-05-18 08:11:26.667586 I DevRdB(/dev/video1): buffer size 9400 KB |
|---|
| 281 | 2012-05-18 08:11:26.667598 I MPEGRec(/dev/video1): DRB ready |
|---|
| 282 | 2012-05-18 08:11:26.667608 I MPEGRec(/dev/video1): Initial startup of recorder |
|---|
| 283 | 2012-05-18 08:11:26.667612 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 284 | 2012-05-18 08:11:26.668958 I TVRec(2): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, |
|---|
| 285 | 2012-05-18 08:11:26.669633 I TVRec(2): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
|---|
| 286 | 2012-05-18 08:11:27.058455 I MPEGRec(/dev/video1): Encoding started |
|---|
| 287 | 2012-05-18 08:11:27.058467 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 288 | 2012-05-18 08:11:27.058508 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 289 | 2012-05-18 08:11:27.058510 I DevRdB(/dev/video1): Start() -- end |
|---|
| 290 | 2012-05-18 08:11:27.148668 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb.py -l en -M AB Twister |
|---|
| 291 | 2012-05-18 08:11:27.405112 E Preview: Encountered problems running '/usr/bin/mythpreviewgen --size 0x0 --chanid 1017 --starttime 20120518081100 --outfile "/myth/1017_20120518081100.mpg.png" --verbose general,record,channel --loglevel info --quiet' (128) |
|---|
| 292 | 2012-05-18 08:11:27.482657 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 293 | 2012-05-18 08:11:27.482718 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 294 | 2012-05-18 08:11:27.492737 I MainServer::ANN Monitor |
|---|
| 295 | 2012-05-18 08:11:27.492745 I adding: mythserver as a client (events: 2) |
|---|
| 296 | 2012-05-18 08:11:27.498167 I MainServer::ANN Monitor |
|---|
| 297 | 2012-05-18 08:11:27.498173 I adding: mythserver as a client (events: 2) |
|---|
| 298 | 2012-05-18 08:11:27.505501 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -M AB Twister |
|---|
| 299 | 2012-05-18 08:11:27.830539 I MPEGRec(/dev/video0): Encoding started |
|---|
| 300 | 2012-05-18 08:11:27.830577 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 301 | 2012-05-18 08:11:27.830656 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 302 | 2012-05-18 08:11:27.830666 I DevRdB(/dev/video0): Start() -- end |
|---|
| 303 | 2012-05-18 08:11:28.216448 I MainServer::ANN Monitor |
|---|
| 304 | 2012-05-18 08:11:28.216457 I adding: mythserver as a client (events: 2) |
|---|
| 305 | 2012-05-18 08:11:28.217090 I Reschedule requested for id 412. |
|---|
| 306 | 2012-05-18 08:11:29.559126 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 307 | 2012-05-18 08:11:29.559153 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 308 | 2012-05-18 08:11:29.559393 E MPEGRec(/dev/video1): Device error detected |
|---|
| 309 | 2012-05-18 08:11:29.559412 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 310 | 2012-05-18 08:11:29.559423 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 311 | 2012-05-18 08:11:30.333445 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 312 | 2012-05-18 08:11:30.333454 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 313 | 2012-05-18 08:11:30.333497 E MPEGRec(/dev/video0): Device error detected |
|---|
| 314 | 2012-05-18 08:11:30.333505 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 315 | 2012-05-18 08:11:30.333509 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 316 | 2012-05-18 08:11:31.658522 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 317 | 2012-05-18 08:11:31.658553 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 318 | 2012-05-18 08:11:31.850859 I Scheduled 1509 items in 3.6 = 0.06 match + 3.57 place |
|---|
| 319 | 2012-05-18 08:11:31.853191 I TVRec(3): RecordPending on inputid 3 |
|---|
| 320 | 2012-05-18 08:11:31.853396 I TVRec(3): StartRecording("Paid Programming") |
|---|
| 321 | 2012-05-18 08:11:32.025924 I TVRec(3): StartedRecording(1020_2012-05-18T08:12:00) fn(/myth/1020_20120518081200.mpg) |
|---|
| 322 | 2012-05-18 08:11:32.025962 I TVRec(3): ClearFlags(CancelNextRecording,) -> RunMainLoop, |
|---|
| 323 | 2012-05-18 08:11:32.026002 I TVRec(3): Changing from None to RecordingOnly |
|---|
| 324 | 2012-05-18 08:11:32.026016 I TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, |
|---|
| 325 | 2012-05-18 08:11:32.026056 I TVRec(3): HandleTuning Request: Program(ProgramInfo(1020_20120518081200.mpg): channame(WUTV) startts(Fri May 18 08:00:00 2012) endts(Fri May 18 08:30:00 2012) |
|---|
| 326 | recstartts(Fri May 18 08:12:00 2012) recendts(Fri May 18 08:30:00 2012) |
|---|
| 327 | title(Paid Programming)) channel() input() flags(Recording,) |
|---|
| 328 | 2012-05-18 08:11:32.027132 I TVRec(3): HW Tuner: 3->3 |
|---|
| 329 | 2012-05-18 08:11:32.027141 I TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, |
|---|
| 330 | 2012-05-18 08:11:32.027146 I TVRec(3): No recorder yet, calling TuningFrequency |
|---|
| 331 | 2012-05-18 08:11:32.027594 I V4LChannel(/dev/video2): Device name 'WinTV PVR 500 (unit #2)' driver 'ivtv'. |
|---|
| 332 | 2012-05-18 08:11:32.027603 I V4LChannel(/dev/video2): v4l2: 1 stream io: 0 std io: 1 async io: 0 tuner 1 sliced vbi 1 |
|---|
| 333 | 2012-05-18 08:11:32.032759 I ChannelBase(3): Input #3: 'Tuner 1' schan(2) sourceid(1) ccid(3) |
|---|
| 334 | 2012-05-18 08:11:32.032768 I ChannelBase(3): Current Input #3: 'Tuner 1' |
|---|
| 335 | 2012-05-18 08:11:32.032808 I Global TVFormat Setting 'NTSC' |
|---|
| 336 | 2012-05-18 08:11:32.032828 I V4LChannel(/dev/video2): Input #3: 'Tuner 1' schan(2) tun() v4l1(Unknown) v4l2(NTSC) |
|---|
| 337 | 2012-05-18 08:11:32.032834 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(3) |
|---|
| 338 | 2012-05-18 08:11:32.032839 I Channel(/dev/video2)::SwitchToInput(in 3, '') |
|---|
| 339 | 2012-05-18 08:11:32.033787 I V4LChannel(/dev/video2): SetInputAndFormat(3, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 |
|---|
| 340 | 2012-05-18 08:11:32.033794 I DTVChan(/dev/video2): SetChannelByString(20): |
|---|
| 341 | 2012-05-18 08:11:32.034940 I V4LChannel(/dev/video2): SetFormat(Default) fmt(NTSC) input(3) |
|---|
| 342 | 2012-05-18 08:11:32.034950 I Channel(/dev/video2)::Tune(20): curList[18].freq(157250) |
|---|
| 343 | 2012-05-18 08:11:32.034954 I V4LChannel(/dev/video2): Tune(157250000, ) |
|---|
| 344 | 2012-05-18 08:11:32.042434 I MPEGRec(/dev/video1): Encoding started |
|---|
| 345 | 2012-05-18 08:11:32.042441 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 346 | 2012-05-18 08:11:32.042472 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 347 | 2012-05-18 08:11:32.042475 I DevRdB(/dev/video1): Start() -- end |
|---|
| 348 | 2012-05-18 08:11:32.050310 I Channel(/dev/video2)::Tune(): Frequency is now 157250000 |
|---|
| 349 | 2012-05-18 08:11:32.050314 I DTVChan(/dev/video2): SetChannelByString(20): success |
|---|
| 350 | 2012-05-18 08:11:32.052026 I TVRec(3): Starting Signal Monitor |
|---|
| 351 | 2012-05-18 08:11:32.052034 I TVRec(3): SetupSignalMonitor(1, 0) |
|---|
| 352 | 2012-05-18 08:11:32.052050 I TVRec(3): Signal monitor successfully created |
|---|
| 353 | 2012-05-18 08:11:32.053139 I TVRec(3): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, |
|---|
| 354 | 2012-05-18 08:11:32.053145 I TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, |
|---|
| 355 | 2012-05-18 08:11:32.053150 I TVRec(3): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
|---|
| 356 | 2012-05-18 08:11:32.053155 I TVRec(3): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, |
|---|
| 357 | 2012-05-18 08:11:32.053167 I TVRec(3): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, |
|---|
| 358 | 2012-05-18 08:11:32.054212 I TVRec(3): Got good signal |
|---|
| 359 | 2012-05-18 08:11:32.054245 I TVRec(3): TeardownSignalMonitor() -- begin |
|---|
| 360 | 2012-05-18 08:11:32.057200 N AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 10 min |
|---|
| 361 | 2012-05-18 08:11:32.057219 I Tuning recording: "Paid Programming": channel 1020 on cardid 3, sourceid 1 |
|---|
| 362 | 2012-05-18 08:11:32.059522 I Updating status for "Paid Programming" on cardid 3 (Tuning => Recording) |
|---|
| 363 | 2012-05-18 08:11:32.104388 I TVRec(3): TeardownSignalMonitor() -- end |
|---|
| 364 | 2012-05-18 08:11:32.104396 I TVRec(3): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder, |
|---|
| 365 | 2012-05-18 08:11:32.104404 I TVRec(3): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder, |
|---|
| 366 | 2012-05-18 08:11:32.104420 I TVRec(3): Starting Recorder |
|---|
| 367 | 2012-05-18 08:11:32.112240 I Using profile 'Default' to record |
|---|
| 368 | 2012-05-18 08:11:32.112249 I TVRec(3): rec->GetPathname(): '/myth/1020_20120518081200.mpg' |
|---|
| 369 | 2012-05-18 08:11:32.112422 I DTVRec(3): ResetForNewFile(void) |
|---|
| 370 | 2012-05-18 08:11:32.112491 I RecBase(3:/dev/video2): SetRingBuffer(0x7f3f1804e850) '/myth/1020_20120518081200.mpg' |
|---|
| 371 | 2012-05-18 08:11:32.112504 I RecBase(3:/dev/video2): SetRecording(0x7f3f1047b480) title(Paid Programming) |
|---|
| 372 | 2012-05-18 08:11:32.116671 I DTVRec(3): ResetForNewFile(void) |
|---|
| 373 | 2012-05-18 08:11:32.129813 I DevRdB(/dev/video2): buffer size 9400 KB |
|---|
| 374 | 2012-05-18 08:11:32.129822 I MPEGRec(/dev/video2): DRB ready |
|---|
| 375 | 2012-05-18 08:11:32.129842 I MPEGRec(/dev/video2): Initial startup of recorder |
|---|
| 376 | 2012-05-18 08:11:32.129850 I MPEGRec(/dev/video2): StartEncoding |
|---|
| 377 | 2012-05-18 08:11:32.134460 I TVRec(3): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, |
|---|
| 378 | 2012-05-18 08:11:32.135196 I TVRec(3): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
|---|
| 379 | 2012-05-18 08:11:32.197365 I MainServer::ANN Monitor |
|---|
| 380 | 2012-05-18 08:11:32.197372 I adding: mythserver as a client (events: 2) |
|---|
| 381 | 2012-05-18 08:11:32.201113 I MainServer::ANN Monitor |
|---|
| 382 | 2012-05-18 08:11:32.201119 I adding: mythserver as a client (events: 2) |
|---|
| 383 | 2012-05-18 08:11:32.430565 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 384 | 2012-05-18 08:11:32.430593 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 385 | 2012-05-18 08:11:32.482452 I MPEGRec(/dev/video2): Encoding started |
|---|
| 386 | 2012-05-18 08:11:32.482478 I DevRdB(/dev/video2): Start() -- begin |
|---|
| 387 | 2012-05-18 08:11:32.482547 I DevRdB(/dev/video2): Start() -- middle |
|---|
| 388 | 2012-05-18 08:11:32.482554 I DevRdB(/dev/video2): Start() -- end |
|---|
| 389 | 2012-05-18 08:11:32.766503 I MPEGRec(/dev/video0): Encoding started |
|---|
| 390 | 2012-05-18 08:11:32.766536 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 391 | 2012-05-18 08:11:32.766678 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 392 | 2012-05-18 08:11:32.766694 I DevRdB(/dev/video0): Start() -- end |
|---|
| 393 | 2012-05-18 08:11:32.778796 I DTVRec(3): FindPSKeyFrames: frame rate = 29970 |
|---|
| 394 | 2012-05-18 08:11:34.553133 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 395 | 2012-05-18 08:11:34.553154 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 396 | 2012-05-18 08:11:34.553284 E MPEGRec(/dev/video1): Device error detected |
|---|
| 397 | 2012-05-18 08:11:34.553308 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 398 | 2012-05-18 08:11:34.553318 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 399 | 2012-05-18 08:11:35.268169 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 400 | 2012-05-18 08:11:35.268209 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 401 | 2012-05-18 08:11:35.268434 E MPEGRec(/dev/video0): Device error detected |
|---|
| 402 | 2012-05-18 08:11:35.268445 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 403 | 2012-05-18 08:11:35.268456 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 404 | 2012-05-18 08:11:36.650618 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 405 | 2012-05-18 08:11:36.650660 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 406 | 2012-05-18 08:11:37.002499 I MPEGRec(/dev/video1): Encoding started |
|---|
| 407 | 2012-05-18 08:11:37.002527 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 408 | 2012-05-18 08:11:37.002605 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 409 | 2012-05-18 08:11:37.002614 I DevRdB(/dev/video1): Start() -- end |
|---|
| 410 | 2012-05-18 08:11:37.049808 I MainServer::ANN Monitor |
|---|
| 411 | 2012-05-18 08:11:37.049815 I adding: mythserver as a client (events: 2) |
|---|
| 412 | 2012-05-18 08:11:37.192405 I MainServer::ANN Monitor |
|---|
| 413 | 2012-05-18 08:11:37.192412 I adding: mythserver as a client (events: 2) |
|---|
| 414 | 2012-05-18 08:11:37.366521 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 415 | 2012-05-18 08:11:37.366539 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 416 | 2012-05-18 08:11:37.710454 I MPEGRec(/dev/video0): Encoding started |
|---|
| 417 | 2012-05-18 08:11:37.710470 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 418 | 2012-05-18 08:11:37.710545 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 419 | 2012-05-18 08:11:37.710549 I DevRdB(/dev/video0): Start() -- end |
|---|
| 420 | 2012-05-18 08:11:37.858349 I MainServer::ANN Monitor |
|---|
| 421 | 2012-05-18 08:11:37.858355 I adding: mythserver as a client (events: 0) |
|---|
| 422 | 2012-05-18 08:11:37.858639 I MainServer::ANN Monitor |
|---|
| 423 | 2012-05-18 08:11:37.858646 I adding: mythserver as a client (events: 1) |
|---|
| 424 | 2012-05-18 08:11:37.882384 I MainServer::ANN Monitor |
|---|
| 425 | 2012-05-18 08:11:37.882399 I adding: mythserver as a client (events: 0) |
|---|
| 426 | 2012-05-18 08:11:37.883218 I MainServer::ANN Monitor |
|---|
| 427 | 2012-05-18 08:11:37.883231 I adding: mythserver as a client (events: 1) |
|---|
| 428 | 2012-05-18 08:11:37.922243 I MainServer::ANN Monitor |
|---|
| 429 | 2012-05-18 08:11:37.922260 I adding: mythserver as a client (events: 0) |
|---|
| 430 | 2012-05-18 08:11:37.923478 I MainServer::ANN Monitor |
|---|
| 431 | 2012-05-18 08:11:37.923496 I adding: mythserver as a client (events: 1) |
|---|
| 432 | 2012-05-18 08:11:39.003927 E Preview: Encountered problems running '/usr/bin/mythpreviewgen --size 0x0 --chanid 1017 --starttime 20120518081100 --outfile "/myth/1017_20120518081100.mpg.png" --verbose general,record,channel --loglevel info --quiet' (128) |
|---|
| 433 | 2012-05-18 08:11:39.003959 E Preview: Encountered problems running '/usr/bin/mythpreviewgen --size 0x0 --chanid 1018 --starttime 20120518081100 --outfile "/myth/1018_20120518081100.mpg.png" --verbose general,record,channel --loglevel info --quiet' (128) |
|---|
| 434 | 2012-05-18 08:11:39.505473 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 435 | 2012-05-18 08:11:39.505498 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 436 | 2012-05-18 08:11:39.505663 E MPEGRec(/dev/video1): Device error detected |
|---|
| 437 | 2012-05-18 08:11:39.505695 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 438 | 2012-05-18 08:11:39.505705 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 439 | 2012-05-18 08:11:39.697542 I Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb.py -l en -M Paid Programming |
|---|
| 440 | 2012-05-18 08:11:40.082755 I MainServer::ANN Monitor |
|---|
| 441 | 2012-05-18 08:11:40.082763 I adding: mythserver as a client (events: 0) |
|---|
| 442 | 2012-05-18 08:11:40.083048 I MainServer::ANN Monitor |
|---|
| 443 | 2012-05-18 08:11:40.083058 I adding: mythserver as a client (events: 1) |
|---|
| 444 | 2012-05-18 08:11:40.112432 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -M Paid Programming |
|---|
| 445 | 2012-05-18 08:11:40.217038 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 446 | 2012-05-18 08:11:40.217047 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 447 | 2012-05-18 08:11:40.217190 E MPEGRec(/dev/video0): Device error detected |
|---|
| 448 | 2012-05-18 08:11:40.217197 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 449 | 2012-05-18 08:11:40.217202 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 450 | 2012-05-18 08:11:40.413731 I Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -D 248938 1 1 |
|---|
| 451 | 2012-05-18 08:11:40.614139 I Returning Metadata Results: Paid Programming 0 0 |
|---|
| 452 | 2012-05-18 08:11:41.007537 I MainServer::ANN Monitor |
|---|
| 453 | 2012-05-18 08:11:41.007542 I adding: mythserver as a client (events: 0) |
|---|
| 454 | 2012-05-18 08:11:41.007824 I MainServer::ANN Monitor |
|---|
| 455 | 2012-05-18 08:11:41.007828 I adding: mythserver as a client (events: 1) |
|---|
| 456 | 2012-05-18 08:11:41.602673 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 457 | 2012-05-18 08:11:41.602730 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 458 | 2012-05-18 08:11:41.958489 I MPEGRec(/dev/video1): Encoding started |
|---|
| 459 | 2012-05-18 08:11:41.958515 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 460 | 2012-05-18 08:11:41.958635 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 461 | 2012-05-18 08:11:41.958645 I DevRdB(/dev/video1): Start() -- end |
|---|
| 462 | 2012-05-18 08:11:42.314717 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 463 | 2012-05-18 08:11:42.314783 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 464 | 2012-05-18 08:11:42.658492 I MPEGRec(/dev/video0): Encoding started |
|---|
| 465 | 2012-05-18 08:11:42.658519 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 466 | 2012-05-18 08:11:42.658609 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 467 | 2012-05-18 08:11:42.658620 I DevRdB(/dev/video0): Start() -- end |
|---|
| 468 | 2012-05-18 08:11:44.461563 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 469 | 2012-05-18 08:11:44.461587 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 470 | 2012-05-18 08:11:44.461734 E MPEGRec(/dev/video1): Device error detected |
|---|
| 471 | 2012-05-18 08:11:44.461759 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 472 | 2012-05-18 08:11:44.461787 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 473 | 2012-05-18 08:11:45.160011 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 474 | 2012-05-18 08:11:45.160049 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 475 | 2012-05-18 08:11:45.160280 E MPEGRec(/dev/video0): Device error detected |
|---|
| 476 | 2012-05-18 08:11:45.160292 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 477 | 2012-05-18 08:11:45.160302 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 478 | 2012-05-18 08:11:46.558601 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 479 | 2012-05-18 08:11:46.558644 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 480 | 2012-05-18 08:11:46.914492 I MPEGRec(/dev/video1): Encoding started |
|---|
| 481 | 2012-05-18 08:11:46.914519 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 482 | 2012-05-18 08:11:46.914656 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 483 | 2012-05-18 08:11:46.914667 I DevRdB(/dev/video1): Start() -- end |
|---|
| 484 | 2012-05-18 08:11:47.258627 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 485 | 2012-05-18 08:11:47.258673 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 486 | 2012-05-18 08:11:47.594543 I MPEGRec(/dev/video0): Encoding started |
|---|
| 487 | 2012-05-18 08:11:47.594571 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 488 | 2012-05-18 08:11:47.594651 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 489 | 2012-05-18 08:11:47.594662 I DevRdB(/dev/video0): Start() -- end |
|---|
| 490 | 2012-05-18 08:11:49.415581 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 491 | 2012-05-18 08:11:49.415632 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 492 | 2012-05-18 08:11:49.415724 E MPEGRec(/dev/video1): Device error detected |
|---|
| 493 | 2012-05-18 08:11:49.415743 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 494 | 2012-05-18 08:11:49.415765 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 495 | 2012-05-18 08:11:50.097520 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 496 | 2012-05-18 08:11:50.097543 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 497 | 2012-05-18 08:11:50.097779 E MPEGRec(/dev/video0): Device error detected |
|---|
| 498 | 2012-05-18 08:11:50.097790 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 499 | 2012-05-18 08:11:50.097800 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 500 | 2012-05-18 08:11:51.514626 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 501 | 2012-05-18 08:11:51.514670 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 502 | 2012-05-18 08:11:51.870499 I MPEGRec(/dev/video1): Encoding started |
|---|
| 503 | 2012-05-18 08:11:51.870526 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 504 | 2012-05-18 08:11:51.870605 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 505 | 2012-05-18 08:11:51.870615 I DevRdB(/dev/video1): Start() -- end |
|---|
| 506 | 2012-05-18 08:11:52.194629 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 507 | 2012-05-18 08:11:52.194676 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 508 | 2012-05-18 08:11:52.530531 I MPEGRec(/dev/video0): Encoding started |
|---|
| 509 | 2012-05-18 08:11:52.530559 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 510 | 2012-05-18 08:11:52.530638 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 511 | 2012-05-18 08:11:52.530650 I DevRdB(/dev/video0): Start() -- end |
|---|
| 512 | 2012-05-18 08:11:54.371222 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 513 | 2012-05-18 08:11:54.371244 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 514 | 2012-05-18 08:11:54.371371 E MPEGRec(/dev/video1): Device error detected |
|---|
| 515 | 2012-05-18 08:11:54.371395 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 516 | 2012-05-18 08:11:54.371406 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 517 | 2012-05-18 08:11:55.031754 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 518 | 2012-05-18 08:11:55.031776 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 519 | 2012-05-18 08:11:55.032002 E MPEGRec(/dev/video0): Device error detected |
|---|
| 520 | 2012-05-18 08:11:55.032014 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 521 | 2012-05-18 08:11:55.032025 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 522 | 2012-05-18 08:11:56.470631 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 523 | 2012-05-18 08:11:56.470676 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 524 | 2012-05-18 08:11:56.826496 I MPEGRec(/dev/video1): Encoding started |
|---|
| 525 | 2012-05-18 08:11:56.826524 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 526 | 2012-05-18 08:11:56.826600 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 527 | 2012-05-18 08:11:56.826610 I DevRdB(/dev/video1): Start() -- end |
|---|
| 528 | 2012-05-18 08:11:57.130608 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 529 | 2012-05-18 08:11:57.130653 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 530 | 2012-05-18 08:11:57.466503 I MPEGRec(/dev/video0): Encoding started |
|---|
| 531 | 2012-05-18 08:11:57.466530 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 532 | 2012-05-18 08:11:57.466613 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 533 | 2012-05-18 08:11:57.466624 I DevRdB(/dev/video0): Start() -- end |
|---|
| 534 | 2012-05-18 08:11:59.329371 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 535 | 2012-05-18 08:11:59.329379 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 536 | 2012-05-18 08:11:59.329488 E MPEGRec(/dev/video1): Device error detected |
|---|
| 537 | 2012-05-18 08:11:59.329510 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 538 | 2012-05-18 08:11:59.329520 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 539 | 2012-05-18 08:11:59.968907 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 540 | 2012-05-18 08:11:59.968928 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 541 | 2012-05-18 08:11:59.969167 E MPEGRec(/dev/video0): Device error detected |
|---|
| 542 | 2012-05-18 08:11:59.969179 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 543 | 2012-05-18 08:11:59.969188 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 544 | 2012-05-18 08:12:01.426625 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 545 | 2012-05-18 08:12:01.426669 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 546 | 2012-05-18 08:12:01.782498 I MPEGRec(/dev/video1): Encoding started |
|---|
| 547 | 2012-05-18 08:12:01.782525 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 548 | 2012-05-18 08:12:01.782601 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 549 | 2012-05-18 08:12:01.782611 I DevRdB(/dev/video1): Start() -- end |
|---|
| 550 | 2012-05-18 08:12:02.066658 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 551 | 2012-05-18 08:12:02.066705 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 552 | 2012-05-18 08:12:02.402529 I MPEGRec(/dev/video0): Encoding started |
|---|
| 553 | 2012-05-18 08:12:02.402556 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 554 | 2012-05-18 08:12:02.402636 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 555 | 2012-05-18 08:12:02.402648 I DevRdB(/dev/video0): Start() -- end |
|---|
| 556 | 2012-05-18 08:12:04.285420 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 557 | 2012-05-18 08:12:04.285441 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 558 | 2012-05-18 08:12:04.285544 E MPEGRec(/dev/video1): Device error detected |
|---|
| 559 | 2012-05-18 08:12:04.285560 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 560 | 2012-05-18 08:12:04.285565 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 561 | 2012-05-18 08:12:04.905482 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 562 | 2012-05-18 08:12:04.905502 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 563 | 2012-05-18 08:12:04.905733 E MPEGRec(/dev/video0): Device error detected |
|---|
| 564 | 2012-05-18 08:12:04.905745 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 565 | 2012-05-18 08:12:04.905755 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 566 | 2012-05-18 08:12:06.382604 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 567 | 2012-05-18 08:12:06.382639 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 568 | 2012-05-18 08:12:06.738503 I MPEGRec(/dev/video1): Encoding started |
|---|
| 569 | 2012-05-18 08:12:06.738530 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 570 | 2012-05-18 08:12:06.738606 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 571 | 2012-05-18 08:12:06.738616 I DevRdB(/dev/video1): Start() -- end |
|---|
| 572 | 2012-05-18 08:12:07.002610 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 573 | 2012-05-18 08:12:07.002656 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 574 | 2012-05-18 08:12:07.076784 N AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 10 min |
|---|
| 575 | 2012-05-18 08:12:07.094231 N Expiring 2225 MB for 1015 at 2011-07-03T21:00:00 => "Celebrity Rehab With Dr. Drew":Intake |
|---|
| 576 | 2012-05-18 08:12:07.098285 E ProgramInfo(1015_20110703210000.mpg): GetPlaybackURL: '1015_20110703210000.mpg' should be local, but it can not be found. |
|---|
| 577 | 2012-05-18 08:12:07.098623 E ERROR when trying to delete file: GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/mythserver/1015_20110703210000.mpg. File doesn't exist. Database metadata will not be removed. |
|---|
| 578 | 2012-05-18 08:12:07.338446 I MPEGRec(/dev/video0): Encoding started |
|---|
| 579 | 2012-05-18 08:12:07.338458 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 580 | 2012-05-18 08:12:07.338501 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 581 | 2012-05-18 08:12:07.338505 I DevRdB(/dev/video0): Start() -- end |
|---|
| 582 | 2012-05-18 08:12:09.241434 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 583 | 2012-05-18 08:12:09.241456 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 584 | 2012-05-18 08:12:09.241589 E MPEGRec(/dev/video1): Device error detected |
|---|
| 585 | 2012-05-18 08:12:09.241610 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 586 | 2012-05-18 08:12:09.241620 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 587 | 2012-05-18 08:12:09.841225 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 588 | 2012-05-18 08:12:09.841246 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 589 | 2012-05-18 08:12:09.841483 E MPEGRec(/dev/video0): Device error detected |
|---|
| 590 | 2012-05-18 08:12:09.841495 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 591 | 2012-05-18 08:12:09.841505 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 592 | 2012-05-18 08:12:11.338621 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 593 | 2012-05-18 08:12:11.338663 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 594 | 2012-05-18 08:12:11.694501 I MPEGRec(/dev/video1): Encoding started |
|---|
| 595 | 2012-05-18 08:12:11.694529 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 596 | 2012-05-18 08:12:11.694606 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 597 | 2012-05-18 08:12:11.694616 I DevRdB(/dev/video1): Start() -- end |
|---|
| 598 | 2012-05-18 08:12:11.938626 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 599 | 2012-05-18 08:12:11.938671 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 600 | 2012-05-18 08:12:12.274511 I MPEGRec(/dev/video0): Encoding started |
|---|
| 601 | 2012-05-18 08:12:12.274541 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 602 | 2012-05-18 08:12:12.274626 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 603 | 2012-05-18 08:12:12.274637 I DevRdB(/dev/video0): Start() -- end |
|---|
| 604 | 2012-05-18 08:12:14.195036 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 605 | 2012-05-18 08:12:14.195045 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 606 | 2012-05-18 08:12:14.195182 E MPEGRec(/dev/video1): Device error detected |
|---|
| 607 | 2012-05-18 08:12:14.195185 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 608 | 2012-05-18 08:12:14.195188 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 609 | 2012-05-18 08:12:14.777464 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 610 | 2012-05-18 08:12:14.777484 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 611 | 2012-05-18 08:12:14.777721 E MPEGRec(/dev/video0): Device error detected |
|---|
| 612 | 2012-05-18 08:12:14.777732 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 613 | 2012-05-18 08:12:14.777742 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 614 | 2012-05-18 08:12:16.294585 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 615 | 2012-05-18 08:12:16.294629 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 616 | 2012-05-18 08:12:16.650503 I MPEGRec(/dev/video1): Encoding started |
|---|
| 617 | 2012-05-18 08:12:16.650530 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 618 | 2012-05-18 08:12:16.650607 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 619 | 2012-05-18 08:12:16.650617 I DevRdB(/dev/video1): Start() -- end |
|---|
| 620 | 2012-05-18 08:12:16.874627 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 621 | 2012-05-18 08:12:16.874673 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 622 | 2012-05-18 08:12:17.210533 I MPEGRec(/dev/video0): Encoding started |
|---|
| 623 | 2012-05-18 08:12:17.210561 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 624 | 2012-05-18 08:12:17.210642 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 625 | 2012-05-18 08:12:17.210653 I DevRdB(/dev/video0): Start() -- end |
|---|
| 626 | 2012-05-18 08:12:19.152384 E DevRdB(/dev/video1): Poll giving up 2 |
|---|
| 627 | 2012-05-18 08:12:19.152405 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 628 | 2012-05-18 08:12:19.152486 E MPEGRec(/dev/video1): Device error detected |
|---|
| 629 | 2012-05-18 08:12:19.152506 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 630 | 2012-05-18 08:12:19.152517 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 631 | 2012-05-18 08:12:19.713518 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 632 | 2012-05-18 08:12:19.713539 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 633 | 2012-05-18 08:12:19.713770 E MPEGRec(/dev/video0): Device error detected |
|---|
| 634 | 2012-05-18 08:12:19.713782 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 635 | 2012-05-18 08:12:19.713791 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 636 | 2012-05-18 08:12:21.132398 I MainServer::ANN Monitor |
|---|
| 637 | 2012-05-18 08:12:21.132404 I adding: mythserver as a client (events: 2) |
|---|
| 638 | 2012-05-18 08:12:21.165656 I MainServer::ANN Monitor |
|---|
| 639 | 2012-05-18 08:12:21.165662 I adding: mythserver as a client (events: 2) |
|---|
| 640 | 2012-05-18 08:12:21.166161 I TVRec(2): Changing from RecordingOnly to None |
|---|
| 641 | 2012-05-18 08:12:21.166177 I TVRec(2): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
|---|
| 642 | 2012-05-18 08:12:21.166187 I TVRec(2): HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,) |
|---|
| 643 | 2012-05-18 08:12:21.250590 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 644 | 2012-05-18 08:12:21.250639 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 645 | 2012-05-18 08:12:21.606499 I MPEGRec(/dev/video1): Encoding started |
|---|
| 646 | 2012-05-18 08:12:21.606529 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 647 | 2012-05-18 08:12:21.606608 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 648 | 2012-05-18 08:12:21.606618 I DevRdB(/dev/video1): Start() -- end |
|---|
| 649 | 2012-05-18 08:12:21.606651 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 650 | 2012-05-18 08:12:21.810587 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 651 | 2012-05-18 08:12:21.810632 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 652 | 2012-05-18 08:12:22.146464 I MPEGRec(/dev/video0): Encoding started |
|---|
| 653 | 2012-05-18 08:12:22.146480 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 654 | 2012-05-18 08:12:22.146523 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 655 | 2012-05-18 08:12:22.146528 I DevRdB(/dev/video0): Start() -- end |
|---|
| 656 | 2012-05-18 08:12:23.706639 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 657 | 2012-05-18 08:12:23.706679 E DevRdB(/dev/video1): poll error |
|---|
| 658 | 2012-05-18 08:12:23.706705 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 659 | 2012-05-18 08:12:23.706802 E MPEGRec(/dev/video1): Device error detected |
|---|
| 660 | 2012-05-18 08:12:23.706820 I MPEGRec(/dev/video1): RestartEncoding |
|---|
| 661 | 2012-05-18 08:12:23.726799 I DevRdB(/dev/video1): Stop() -- begin |
|---|
| 662 | 2012-05-18 08:12:23.726824 I DevRdB(/dev/video1): Stop() -- end |
|---|
| 663 | 2012-05-18 08:12:23.726950 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 664 | 2012-05-18 08:12:23.726978 I MPEGRec(/dev/video1): StartEncoding |
|---|
| 665 | 2012-05-18 08:12:24.090500 I MPEGRec(/dev/video1): Encoding started |
|---|
| 666 | 2012-05-18 08:12:24.090529 I DevRdB(/dev/video1): Start() -- begin |
|---|
| 667 | 2012-05-18 08:12:24.090607 I DevRdB(/dev/video1): Start() -- middle |
|---|
| 668 | 2012-05-18 08:12:24.090617 I DevRdB(/dev/video1): Start() -- end |
|---|
| 669 | 2012-05-18 08:12:24.090634 I MPEGRec(/dev/video1): run finishing up |
|---|
| 670 | 2012-05-18 08:12:24.090643 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 671 | 2012-05-18 08:12:24.112494 I MainServer::ANN Monitor |
|---|
| 672 | 2012-05-18 08:12:24.112500 I adding: mythserver as a client (events: 2) |
|---|
| 673 | 2012-05-18 08:12:24.142715 I MainServer::ANN Monitor |
|---|
| 674 | 2012-05-18 08:12:24.142721 I adding: mythserver as a client (events: 2) |
|---|
| 675 | 2012-05-18 08:12:24.143178 I TVRec(3): Changing from RecordingOnly to None |
|---|
| 676 | 2012-05-18 08:12:24.143190 I TVRec(3): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
|---|
| 677 | 2012-05-18 08:12:24.143198 I TVRec(3): HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,) |
|---|
| 678 | 2012-05-18 08:12:24.143204 I MPEGRec(/dev/video2): StopEncoding |
|---|
| 679 | 2012-05-18 08:12:24.383025 E DevRdB(/dev/video2): poll error |
|---|
| 680 | 2012-05-18 08:12:24.383068 E DevRdB(/dev/video2): fill_ringbuffer: error state |
|---|
| 681 | 2012-05-18 08:12:24.383304 E MPEGRec(/dev/video2): Device error detected |
|---|
| 682 | 2012-05-18 08:12:24.383321 I MPEGRec(/dev/video2): RestartEncoding |
|---|
| 683 | 2012-05-18 08:12:24.383343 I MPEGRec(/dev/video2): Encoding stopped |
|---|
| 684 | 2012-05-18 08:12:24.383374 I MPEGRec(/dev/video2): StopEncoding |
|---|
| 685 | 2012-05-18 08:12:24.383385 I MPEGRec(/dev/video2): StartEncoding |
|---|
| 686 | 2012-05-18 08:12:24.649259 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 687 | 2012-05-18 08:12:24.649279 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 688 | 2012-05-18 08:12:24.649508 E MPEGRec(/dev/video0): Device error detected |
|---|
| 689 | 2012-05-18 08:12:24.649519 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 690 | 2012-05-18 08:12:24.649529 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 691 | 2012-05-18 08:12:24.734419 I MPEGRec(/dev/video2): Encoding started |
|---|
| 692 | 2012-05-18 08:12:24.734429 I DevRdB(/dev/video2): Start() -- begin |
|---|
| 693 | 2012-05-18 08:12:24.734459 I DevRdB(/dev/video2): Start() -- middle |
|---|
| 694 | 2012-05-18 08:12:24.734462 I DevRdB(/dev/video2): Start() -- end |
|---|
| 695 | 2012-05-18 08:12:24.734501 I MPEGRec(/dev/video2): run finishing up |
|---|
| 696 | 2012-05-18 08:12:24.734504 I MPEGRec(/dev/video2): StopEncoding |
|---|
| 697 | 2012-05-18 08:12:26.194754 I MPEGRec(/dev/video1): Encoding stopped |
|---|
| 698 | 2012-05-18 08:12:26.194776 E DevRdB(/dev/video1): poll error |
|---|
| 699 | 2012-05-18 08:12:26.194780 E DevRdB(/dev/video1): fill_ringbuffer: error state |
|---|
| 700 | 2012-05-18 08:12:26.214817 I DevRdB(/dev/video1): Stop() -- begin |
|---|
| 701 | 2012-05-18 08:12:26.214821 I DevRdB(/dev/video1): Stop() -- end |
|---|
| 702 | 2012-05-18 08:12:26.216347 I TVRec(2): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
|---|
| 703 | 2012-05-18 08:12:26.216386 I MPEGRec(/dev/video1): StopEncoding |
|---|
| 704 | 2012-05-18 08:12:26.216412 I RecBase(2:/dev/video1): SetRecording(0x0) |
|---|
| 705 | 2012-05-18 08:12:26.217435 I TVRec(2): FinishedRecording(1018_2012-05-18T08:11:00) good recq:<RecordingQuality overall_score="1" key="1018_2012-05-18T08:11:00" /> |
|---|
| 706 | |
|---|
| 707 | 2012-05-18 08:12:26.217481 I TVRec(2): FinishedRecording(1018_2012-05-18T08:11:00) |
|---|
| 708 | title: AB Twister |
|---|
| 709 | in recgroup: Default status: Recording:Recorded not_dummy finished_now |
|---|
| 710 | 2012-05-18 08:12:26.217901 I SaveVideoProperties(0x38, 0x00) |
|---|
| 711 | 2012-05-18 08:12:26.218626 I Updating status for "AB Twister" on cardid 2 (Recording => Recorded) |
|---|
| 712 | 2012-05-18 08:12:26.219839 I Reschedule requested for id 0. |
|---|
| 713 | 2012-05-18 08:12:26.223534 I Finished recording AB Twister: channel 1018 |
|---|
| 714 | 2012-05-18 08:12:26.225013 I TVRec(2): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
|---|
| 715 | 2012-05-18 08:12:26.225026 I TVRec(2): Tearing down RingBuffer |
|---|
| 716 | 2012-05-18 08:12:26.225600 I TVRec(2): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, |
|---|
| 717 | 2012-05-18 08:12:26.225653 I TVRec(2): ClearFlags(CancelNextRecording,KillRec,) -> RunMainLoop,RingBufferReady, |
|---|
| 718 | 2012-05-18 08:12:26.746566 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 719 | 2012-05-18 08:12:26.746594 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 720 | 2012-05-18 08:12:26.834620 I MPEGRec(/dev/video2): Encoding stopped |
|---|
| 721 | 2012-05-18 08:12:26.834909 E DevRdB(/dev/video2): poll error |
|---|
| 722 | 2012-05-18 08:12:26.834924 E DevRdB(/dev/video2): fill_ringbuffer: error state |
|---|
| 723 | 2012-05-18 08:12:26.854776 I DevRdB(/dev/video2): Stop() -- begin |
|---|
| 724 | 2012-05-18 08:12:26.854800 I DevRdB(/dev/video2): Stop() -- end |
|---|
| 725 | 2012-05-18 08:12:26.973169 I TVRec(3): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
|---|
| 726 | 2012-05-18 08:12:26.973238 I MPEGRec(/dev/video2): StopEncoding |
|---|
| 727 | 2012-05-18 08:12:26.973326 I RecBase(3:/dev/video2): SetRecording(0x0) |
|---|
| 728 | 2012-05-18 08:12:26.974703 I TVRec(3): FinishedRecording(1020_2012-05-18T08:12:00) good recq:<RecordingQuality overall_score="1" key="1020_2012-05-18T08:12:00" /> |
|---|
| 729 | |
|---|
| 730 | 2012-05-18 08:12:26.974831 I TVRec(3): FinishedRecording(1020_2012-05-18T08:12:00) |
|---|
| 731 | title: Paid Programming |
|---|
| 732 | in recgroup: Default status: Recording:Recorded not_dummy finished_now |
|---|
| 733 | 2012-05-18 08:12:26.976509 I SaveVideoProperties(0x38, 0x00) |
|---|
| 734 | 2012-05-18 08:12:26.979416 I Finished recording Paid Programming: channel 1020 |
|---|
| 735 | 2012-05-18 08:12:26.979780 I TVRec(3): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
|---|
| 736 | 2012-05-18 08:12:26.979786 I TVRec(3): Tearing down RingBuffer |
|---|
| 737 | 2012-05-18 08:12:26.979993 I TVRec(3): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, |
|---|
| 738 | 2012-05-18 08:12:26.981019 I TVRec(3): ClearFlags(CancelNextRecording,KillRec,) -> RunMainLoop,RingBufferReady, |
|---|
| 739 | 2012-05-18 08:12:26.981041 I Updating status for "Paid Programming" on cardid 3 (Recording => Recorded) |
|---|
| 740 | 2012-05-18 08:12:27.082496 I MPEGRec(/dev/video0): Encoding started |
|---|
| 741 | 2012-05-18 08:12:27.082524 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 742 | 2012-05-18 08:12:27.082603 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 743 | 2012-05-18 08:12:27.082614 I DevRdB(/dev/video0): Start() -- end |
|---|
| 744 | 2012-05-18 08:12:27.893600 E MainServer: PREVIEW_SUCCESS but no receivers. |
|---|
| 745 | 2012-05-18 08:12:27.894897 I MainServer::ANN Monitor |
|---|
| 746 | 2012-05-18 08:12:27.894902 I adding: mythserver as a client (events: 2) |
|---|
| 747 | 2012-05-18 08:12:27.934698 I MainServer::ANN Monitor |
|---|
| 748 | 2012-05-18 08:12:27.934704 I adding: mythserver as a client (events: 2) |
|---|
| 749 | 2012-05-18 08:12:27.935455 I TVRec(1): Changing from RecordingOnly to None |
|---|
| 750 | 2012-05-18 08:12:27.935472 I TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, |
|---|
| 751 | 2012-05-18 08:12:27.935484 I TVRec(1): HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,) |
|---|
| 752 | 2012-05-18 08:12:27.935491 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 753 | 2012-05-18 08:12:28.996749 I Reschedule interrupted, will retry |
|---|
| 754 | 2012-05-18 08:12:28.997030 I Reschedule requested for id 0. |
|---|
| 755 | 2012-05-18 08:12:28.997178 I Reschedule requested for id 0. |
|---|
| 756 | 2012-05-18 08:12:28.997225 I Reschedule requested for id 0. |
|---|
| 757 | 2012-05-18 08:12:29.585349 E DevRdB(/dev/video0): Poll giving up 2 |
|---|
| 758 | 2012-05-18 08:12:29.585360 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 759 | 2012-05-18 08:12:29.585443 E MPEGRec(/dev/video0): Device error detected |
|---|
| 760 | 2012-05-18 08:12:29.585447 I MPEGRec(/dev/video0): RestartEncoding |
|---|
| 761 | 2012-05-18 08:12:30.034589 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 762 | 2012-05-18 08:12:30.034659 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 763 | 2012-05-18 08:12:30.034671 I MPEGRec(/dev/video0): StartEncoding |
|---|
| 764 | 2012-05-18 08:12:30.390502 I MPEGRec(/dev/video0): Encoding started |
|---|
| 765 | 2012-05-18 08:12:30.390530 I DevRdB(/dev/video0): Start() -- begin |
|---|
| 766 | 2012-05-18 08:12:30.390609 I DevRdB(/dev/video0): Start() -- middle |
|---|
| 767 | 2012-05-18 08:12:30.390620 I DevRdB(/dev/video0): Start() -- end |
|---|
| 768 | 2012-05-18 08:12:30.390648 I MPEGRec(/dev/video0): run finishing up |
|---|
| 769 | 2012-05-18 08:12:30.390658 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 770 | 2012-05-18 08:12:31.689489 I Scheduled 1509 items in 2.7 = 0.00 match + 2.69 place |
|---|
| 771 | 2012-05-18 08:12:32.490643 I MPEGRec(/dev/video0): Encoding stopped |
|---|
| 772 | 2012-05-18 08:12:32.490687 E DevRdB(/dev/video0): poll error |
|---|
| 773 | 2012-05-18 08:12:32.490729 E DevRdB(/dev/video0): fill_ringbuffer: error state |
|---|
| 774 | 2012-05-18 08:12:32.510804 I DevRdB(/dev/video0): Stop() -- begin |
|---|
| 775 | 2012-05-18 08:12:32.510830 I DevRdB(/dev/video0): Stop() -- end |
|---|
| 776 | 2012-05-18 08:12:32.684105 I TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
|---|
| 777 | 2012-05-18 08:12:32.684174 I MPEGRec(/dev/video0): StopEncoding |
|---|
| 778 | 2012-05-18 08:12:32.684246 I RecBase(1:/dev/video0): SetRecording(0x0) |
|---|
| 779 | 2012-05-18 08:12:32.685385 I TVRec(1): FinishedRecording(1017_2012-05-18T08:11:00) good recq:<RecordingQuality overall_score="1" key="1017_2012-05-18T08:11:00" /> |
|---|
| 780 | |
|---|
| 781 | 2012-05-18 08:12:32.685503 I TVRec(1): FinishedRecording(1017_2012-05-18T08:11:00) |
|---|
| 782 | title: CBS This Morning |
|---|
| 783 | in recgroup: Default status: Recording:Recorded not_dummy finished_now |
|---|
| 784 | 2012-05-18 08:12:32.686896 I SaveVideoProperties(0x38, 0x00) |
|---|
| 785 | 2012-05-18 08:12:32.690825 I Finished recording CBS This Morning: channel 1017 |
|---|
| 786 | 2012-05-18 08:12:32.691167 I TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, |
|---|
| 787 | 2012-05-18 08:12:32.691174 I TVRec(1): Tearing down RingBuffer |
|---|
| 788 | 2012-05-18 08:12:32.694039 I TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, |
|---|
| 789 | 2012-05-18 08:12:32.695069 I TVRec(1): ClearFlags(CancelNextRecording,KillRec,) -> RunMainLoop,RingBufferReady, |
|---|
| 790 | 2012-05-18 08:12:32.695093 I Updating status for "CBS This Morning" on cardid 1 (Recording => Recorded) |
|---|
| 791 | 2012-05-18 08:12:32.696005 I Reschedule requested for id 0. |
|---|
| 792 | 2012-05-18 08:12:32.697802 W MainServer: Unknown socket closing MythSocket(0x2771010) |
|---|
| 793 | 2012-05-18 08:12:32.698895 E MythSocket(2771010:-1): writeStringList: Error, socket went unconnected. |
|---|
| 794 | We wrote 0 of 53 bytes with 1 errors |
|---|
| 795 | starts with: 45 BACKEND_MESSAGE[]:[]SCHEDULE_CHANGE[]:[]empty |
|---|
| 796 | 2012-05-18 08:12:35.383495 I Scheduled 1509 items in 2.7 = 0.00 match + 2.68 place |
|---|
| 797 | 2012-05-18 08:12:35.386275 I Reschedule requested for id 0. |
|---|
| 798 | 2012-05-18 08:12:38.693457 I Scheduled 1509 items in 3.3 = 0.00 match + 3.30 place |
|---|
| 799 | 2012-05-18 08:12:38.990605 N MythBackend exiting |
|---|
| 800 | 2012-05-18 08:12:39.008266 I Bonjour: De-registering service '_mythbackend-master._tcp.' on 'Mythbackend on mythserver' |
|---|
| 801 | 2012-05-18 08:12:40.935830 I TVRec(1): ClearFlags(RunMainLoop,) -> RingBufferReady, |
|---|
| 802 | 2012-05-18 08:12:40.936547 I TVRec(1): ClearFlags(RecorderRunning,) -> RingBufferReady, |
|---|
| 803 | 2012-05-18 08:12:40.936591 I TVRec(2): ClearFlags(RunMainLoop,) -> RingBufferReady, |
|---|
| 804 | 2012-05-18 08:12:40.937411 I TVRec(2): ClearFlags(RecorderRunning,) -> RingBufferReady, |
|---|
| 805 | 2012-05-18 08:12:40.937453 I TVRec(3): ClearFlags(RunMainLoop,) -> RingBufferReady, |
|---|
| 806 | 2012-05-18 08:12:40.938264 I TVRec(3): ClearFlags(RecorderRunning,) -> RingBufferReady, |
|---|
| 807 | 2012-05-18 08:12:40.938316 I Waiting for threads to exit. |
|---|
