[SOLVED] 18.04 0.29 scheduled recording fails

Have a MythTV related problem? Ask for help from other MythTV users here.

Moderator: Forum Moderators

Post Reply
pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

[SOLVED] 18.04 0.29 scheduled recording fails

Post by pgreenwood » Sat Dec 01, 2018 4:46 am

On this dist-upgrade of Mythbuntu 16.04 to Bionic Beaver 18.04 I can't seem to get scheduled recordings to record. My 16.04 installation just quit working so I upgraded to 18.04/0.29 and restored from the latest DB backup I had. All indications in the FE are that the recording was successful but the recording does not appear in the folder /var/lib/mythtv/recordings or in the Media/Watch Recordings section.

For example, I tried to record General Hospital today at 1 PM (13:00). Here's a snippet from the mythbackend log:

Code: Select all

Nov 14 12:52:17 mythtv mythbackend: mythbackend[851]: E Expire programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2010_20181113010001.ts): GetPlaybackURL: '2010_20181113010001.ts' sho
Nov 14 12:52:17 mythtv mythbackend: mythbackend[851]: E Expire programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2071_20181113190000.ts): GetPlaybackURL: '2071_20181113190000.ts' sho
Nov 14 12:52:17 mythtv mythbackend: mythbackend[851]: E Expire programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2071_20181113190001.ts): GetPlaybackURL: '2071_20181113190001.ts' sho
Nov 14 12:53:48 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Playback
Nov 14 12:53:48 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(221e850) as a client (events: 0)
Nov 14 12:53:48 mythtv mythbackend: mythbackend[851]: I MythSocketThread(80) mainserver.cpp:7629 (connectionClosed) Playback sock(221e850) 'mythtv' disconnected
Nov 14 12:53:48 mythtv mythbackend: mythbackend[851]: I MythSocketThread(84) mainserver.cpp:7657 (connectionClosed) FileTransfer sock(2225560) disconnected
Nov 14 12:59:00 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2267 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
Nov 14 12:59:00 mythtv mythbackend: mythbackend[851]: I Scheduler mythdbcon.cpp:254 (Reconnect) MySQL reconnected successfully
Nov 14 12:59:00 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2380 (HandleReschedule) Scheduled 14 items in 0.4 = 0.00 match + 0.00 check + 0.44 place
Nov 14 12:59:30 mythtv mythbackend: mythbackend[851]: I Scheduler mythdbcon.cpp:422 (PurgeIdleConnections) New DB connection, total: 11
Nov 14 12:59:30 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:1610 (HandlePendingRecordings) TVRec[7]: ASK_RECORDING 7 29 0 0
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: C CoreContext programinfo.cpp:351 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[7]: Changing from None to RecordingOnly
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: I TVRecEvent mythdbcon.cpp:422 (PurgeIdleConnections) New DB connection, total: 11
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:3563 (TuningCheckForHWChange) TVRec[7]: HW Tuner: 7->7
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:3685 (TuningFrequency) TVRec[7]: TuningFrequency
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: E TVRecEvent dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams -- Unknown tuner type = 0xffffffff80000000
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: E TVRecEvent recorders/dtvchannel.cpp:299 (SetChannelByString) DTVChan[7](103C4AEC-0): SetChannelByString(7_1): Failed to initia
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: E TVRecEvent tv_rec.cpp:3763 (TuningFrequency) TVRec[7]: Failed to set channel to 7_1. Reverting to kState_None
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[7]: Changing from RecordingOnly to None
Nov 14 13:00:00 mythtv mythbackend: mythbackend[851]: I CoreContext scheduler.cpp:725 (UpdateRecStatus) Updating status for "General Hospital" on cardid 7 (Will Record => Recorder Fa
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2819 (HandleRecordingStatusChange) Tuning recording: "General Hospital": channel 2071 on cardid 7, sou
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2267 (HandleReschedule) Reschedule requested for CHECK -9 42 0 UpdateRecStatus2 | General Hospital |
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: E Scheduler recordinginfo.cpp:1025 (InsertProgram) RecordingInfo::InsertProgram(ProgramInfo(2071_20181114190000.ts): channame(KE
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[7]: Changing from None to RecordingOnly
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:3563 (TuningCheckForHWChange) TVRec[7]: HW Tuner: 7->7
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:3685 (TuningFrequency) TVRec[7]: TuningFrequency
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: E TVRecEvent dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams -- Unknown tuner type = 0xffffffff80000000
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: E TVRecEvent recorders/dtvchannel.cpp:299 (SetChannelByString) DTVChan[7](103C4AEC-0): SetChannelByString(7_1): Failed to initia
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: E TVRecEvent tv_rec.cpp:3763 (TuningFrequency) TVRec[7]: Failed to set channel to 7_1. Reverting to kState_None
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I TVRecEvent tv_rec.cpp:1073 (HandleStateChange) TVRec[7]: Changing from RecordingOnly to None
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I CoreContext scheduler.cpp:725 (UpdateRecStatus) Updating status for "General Hospital" on cardid 7 (Tuning => Recorder Failed)
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: E Scheduler scheduler.cpp:787 (ChangeRecordingEnd) Failed to change end time on card 7 to 2018-11-14T20:00:00Z
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2371 (HandleReschedule) Reschedule interrupted, will retry
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2267 (HandleReschedule) Reschedule requested for CHECK -9 42 0 UpdateRecStatus2 | General Hospital |
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2267 (HandleReschedule) Reschedule requested for PLACE Interrupted
Nov 14 13:00:01 mythtv mythbackend: mythbackend[851]: I Scheduler scheduler.cpp:2380 (HandleReschedule) Scheduled 14 items in 0.1 = 0.00 match + 0.03 check + 0.03 place
Nov 14 13:00:06 mythtv mythbackend: mythbackend[851]: N Update autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Nov 14 13:01:06 mythtv mythbackend: mythbackend[851]: E JobQueue programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2071_20181114190000.ts): GetPlaybackURL: '2071_20181114190000.ts' s
Nov 14 13:01:06 mythtv mythbackend: mythbackend[851]: E JobQueue programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2071_20181114190000.ts): GetPlaybackURL: '2071_20181114190000.ts' s
Nov 14 13:01:06 mythtv mythbackend: mythbackend[851]: I Metadata_6503 jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "General Hospital" recorded fr
Nov 14 13:01:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 14 13:01:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(2226700) as a client (events: 0)
Nov 14 13:01:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 14 13:01:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(22a2fb0) as a client (events: 1)
Nov 14 13:01:20 mythtv mythbackend: mythbackend[851]: I MythSocketThread(80) mainserver.cpp:7629 (connectionClosed) Monitor sock(22a2fb0) 'mythtv' disconnected
Nov 14 13:01:20 mythtv mythbackend: mythbackend[851]: I MythSocketThread(77) mainserver.cpp:7629 (connectionClosed) Monitor sock(2226700) 'mythtv' disconnected
Nov 14 13:02:11 mythtv mythbackend: mythbackend[851]: E JobQueue programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2071_20181114190001.ts): GetPlaybackURL: '2071_20181114190001.ts' s
Nov 14 13:02:11 mythtv mythbackend: mythbackend[851]: E JobQueue programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2071_20181114190001.ts): GetPlaybackURL: '2071_20181114190001.ts' s
Nov 14 13:02:11 mythtv mythbackend: mythbackend[851]: I Metadata_6504 jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "General Hospital" recorded fr
Nov 14 13:02:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 14 13:02:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(2230150) as a client (events: 0)
Nov 14 13:02:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 14 13:02:13 mythtv mythbackend: mythbackend[851]: I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding: mythtv(221d4f0) as a client (events: 1)
Nov 14 13:02:20 mythtv mythbackend: mythbackend[851]: I MythSocketThread(80) mainserver.cpp:7629 (connectionClosed) Monitor sock(221d4f0) 'mythtv' disconnected
Nov 14 13:02:20 mythtv mythbackend: mythbackend[851]: I MythSocketThread(77) mainserver.cpp:7629 (connectionClosed) Monitor sock(2230150) 'mythtv' disconnected
Nov 14 13:08:17 mythtv mythbackend: mythbackend[851]: N Expire autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Nov 14 13:08:17 mythtv mythbackend: mythbackend[851]: E Expire programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2055_20180522023000.ts): GetPlaybackURL: '2055_20180522023000.ts' sho
Nov 14 13:08:17 mythtv mythbackend: mythbackend[851]: E Expire programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2055_20180522023001.ts): GetPlaybackURL: '2055_20180522023001.ts' sho
Nov 14 13:08:17 mythtv mythbackend: mythbackend[851]: E Expire programinfo.cpp:2594 (GetPlaybackURL) ProgramInfo(2055_20180715153000.ts): GetPlaybackURL: '2055_20180715153000.ts' sho
The only other thing I consider odd is the tuners (HDHR3-US) show up as "Encoder 5" and "Encoder 7". I would have expected Encoders 1 and 3. Also I don't recognize Channel 2071. Thank you for anything you see out of whack.
Last edited by pgreenwood on Fri Feb 08, 2019 10:24 pm, edited 1 time in total.

User avatar
Steve Goodey
Moderator
Posts: 68
Joined: Fri Feb 07, 2014 6:30 pm
Location: Colchester, England
Great Britain

Re: 18.04 0.29 scheduled recording fails

Post by Steve Goodey » Sat Dec 01, 2018 10:38 am

Unfortunately that snippet of log is truncated. i.e.
Failed to initia
Also that log has instances of
Recorder Failed
and
2055_20180522023000.ts' sho
which I think is a truncated version of saying it can't find the file.

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by pgreenwood » Sat Dec 01, 2018 3:18 pm

Steve Goodey wrote:
Sat Dec 01, 2018 10:38 am
Unfortunately that snippet of log is truncated...
Thank you for the prompt reply, Steve. I included the wrong day's information (and truncated the code reference). Forgive the rookie mistakes!

The [correct] log of interest is

Code: Select all

Nov 30 12:54:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Nov 30 12:59:00 mythtv mythbackend: mythbackend[19855]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
Nov 30 12:59:00 mythtv mythbackend: mythbackend[19855]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 10 items in 0.0 = 0.00 match + 0.00 check + 0.02 place
Nov 30 12:59:30 mythtv mythbackend: mythbackend[19855]: I Scheduler mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Nov 30 12:59:31 mythtv mythbackend: mythbackend[19855]: I TVRecEvent tv_rec.cpp:1625 (HandlePendingRecordings) TVRec[5]: ASK_RECORDING 5 28 0 0
Nov 30 13:00:00 mythtv mythbackend: mythbackend[19855]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[5]: Changing from None to RecordingOnly
Nov 30 13:00:00 mythtv mythbackend: mythbackend[19855]: I TVRecEvent mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Nov 30 13:00:00 mythtv mythbackend: mythbackend[19855]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[5]: TuningFrequency
Nov 30 13:00:00 mythtv mythbackend: mythbackend[19855]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Nov 30 13:00:00 mythtv mythbackend: mythbackend[19855]: I Scheduler scheduler.cpp:2923 (HandleRecordingStatusChange) Tuning recording: "General Hospital": channel 1071 on cardid [5],
 sourceid 1
Nov 30 13:00:02 mythtv mythbackend: mythbackend[19855]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "General Hospital" on cardid [5] (Tuning => Recording)
Nov 30 13:00:02 mythtv mythbackend: mythbackend[19855]: I TVRecEvent tv_rec.cpp:4192 (TuningNewRecorder) TVRec[5]: rec->GetPathname(): '/var/lib/mythtv/recordings/1071_20181130190000
.ts'
Nov 30 13:00:02 mythtv mythbackend: mythbackend[19855]: I TVRecEvent tv_rec.cpp:4224 (TuningNewRecorder) TVRec[5]: TuningNewRecorder - CreateRecorder()
Nov 30 13:00:02 mythtv mythbackend: mythbackend[19855]: E TVRecEvent recorders/recorderbase.cpp:232 (SetStrOption) RecBase[5](103C4AEC-0): SetStrOption(...recordingtype): Option not 
in profile.
Nov 30 13:09:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Nov 30 13:23:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Nov 30 13:36:50 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Nov 30 13:36:50 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10d3c770) as a client (events: 0)
Nov 30 13:36:50 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(83) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(562d10da58c0) disconnected
Nov 30 13:36:50 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(81) mainserver.cpp:7643 (connectionClosed) Playback sock(562d10d3c770) 'mythtv' disconnected
Nov 30 13:38:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Nov 30 13:52:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Nov 30 14:00:00 mythtv mythbackend: mythbackend[19855]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[5]: Changing from RecordingOnly to None
Nov 30 14:00:01 mythtv mythbackend: mythbackend[19855]: N RecThread recorders/recorderbase.cpp:501 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (
1920x1080 A/R: 3 29.97fps) Audio Codec: ac3
Nov 30 14:00:01 mythtv mythbackend: mythbackend[19855]: I TVRecEvent recordinginfo.cpp:1186 (FinishedRecording) Finished recording General Hospital: channel 1071
Nov 30 14:00:01 mythtv mythbackend: mythbackend[19855]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "General Hospital" on cardid [5] (Recording => Recorded)
Nov 30 14:00:01 mythtv mythbackend: mythbackend[19855]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -3 392 0 UpdateRecStatus2 | General Hospital |  | Ryan dotes on Ava; Curtis relays bad news; Drew and Kim meet with Oscar. | EP000018074190
Nov 30 14:00:02 mythtv mythbackend: mythbackend[19855]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 9 items in 0.5 = 0.19 match + 0.11 check + 0.22 place
Nov 30 14:00:08 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:00:08 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10cfb730) as a client (events: 0)
Nov 30 14:00:08 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:00:08 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10da58c0) as a client (events: 1)
Nov 30 14:00:19 mythtv mythbackend: mythbackend[19855]: I Metadata_6517 jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "General Hospital" recorded 
from channel 1071 at 2018-11-30T19:00:00Z
Nov 30 14:00:23 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(55) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10cfb730) 'mythtv' disconnected
Nov 30 14:00:23 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(68) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10da58c0) 'mythtv' disconnected
Nov 30 14:00:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:00:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10e1ec30) as a client (events: 0)
Nov 30 14:00:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:00:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10d55ac0) as a client (events: 1)
Nov 30 14:00:34 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(65) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10d55ac0) 'mythtv' disconnected
Nov 30 14:00:34 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(55) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10e1ec30) 'mythtv' disconnected
Nov 30 14:01:24 mythtv mythbackend: mythbackend[19855]: I Commflag_6518 jobqueue.cpp:2282 (DoFlagCommercialsThread) JobQueue: Commercial Detection Starting for "General Hospital" recorded from channel 1071 at 2018-11-30T19:00:00Z
Nov 30 14:01:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:01:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10db73e0) as a client (events: 0)
Nov 30 14:01:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:01:27 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10cfb730) as a client (events: 1)
Nov 30 14:01:35 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(65) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10cfb730) 'mythtv' disconnected
Nov 30 14:01:35 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(55) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10db73e0) 'mythtv' disconnected
Nov 30 14:01:35 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:01:35 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10db73e0) as a client (events: 0)
Nov 30 14:01:35 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov 30 14:01:35 mythtv mythbackend: mythbackend[19855]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(562d10d55ac0) as a client (events: 1)
Nov 30 14:01:42 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(65) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10d55ac0) 'mythtv' disconnected
Nov 30 14:01:42 mythtv mythbackend: mythbackend[19855]: I MythSocketThread(55) mainserver.cpp:7643 (connectionClosed) Monitor sock(562d10db73e0) 'mythtv' disconnected
Nov 30 14:07:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Nov 30 14:22:40 mythtv mythbackend: mythbackend[19855]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
...and let me know if there's anything else you'd like to see.

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by pgreenwood » Tue Dec 04, 2018 11:44 am

What if I just did a complete reinstall. Can old recordings be imported into the recordings directory and have them added to the database so it knows they're there?

User avatar
Steve Goodey
Moderator
Posts: 68
Joined: Fri Feb 07, 2014 6:30 pm
Location: Colchester, England
Great Britain

Re: 18.04 0.29 scheduled recording fails

Post by Steve Goodey » Tue Dec 04, 2018 12:49 pm

Can old recordings be imported into the recordings directory and have them added to the database so it knows they're there?
You can't just move old recordings into the recordings directory on a new install and for them to be seen. You need to follow:-

https://www.mythtv.org/wiki/Database_Backup_and_Restore

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by pgreenwood » Fri Feb 01, 2019 7:52 pm

Back again. Its a new year. One more effort before I completely uninstall and reinstall this mythtv 0.29 running on Ubuntu 18.04.

My installation currently successfully records one (only) title faithfully. That is Masterpiece Theater on PBS which runs on Sunday nights at 8:00 P.M. (20:00 hours)

Here's the mythbackend log for Masterpiece Theater

Code: Select all

Jan 27 18:02:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 18:18:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 18:33:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 18:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 27 18:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787ec940) as a client (events: 0)
Jan 27 18:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787ec940) 'mythtv' disconnected
Jan 27 18:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(55957876e410) disconnected
Jan 27 18:48:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 19:00:00 mythtv mythbackend: mythbackend[1342]: I Scheduler mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Jan 27 19:03:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 19:18:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 19:34:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 19:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 27 19:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957884a250) as a client (events: 0)
Jan 27 19:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(55957884a250) 'mythtv' disconnected
Jan 27 19:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787b1d50) disconnected
Jan 27 19:49:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 19:59:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
Jan 27 19:59:00 mythtv mythbackend: mythbackend[1342]: I Scheduler mythdbcon.cpp:267 (Reconnect) MySQL reconnected successfully
Jan 27 19:59:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 17 items in 0.0 = 0.00 match + 0.00 check + 0.04 place
Jan 27 19:59:30 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:1625 (HandlePendingRecordings) TVRec[5]: ASK_RECORDING 5 29 0 0
Jan 27 20:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[5]: Changing from None to RecordingOnly
Jan 27 20:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Jan 27 20:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[5]: TuningFrequency
Jan 27 20:00:00 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Jan 27 20:00:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2923 (HandleRecordingStatusChange) Tuning recording: "Victoria on Masterpiece":"Et in Arcadia": channel 1121 on cardid [5], sourceid 1
Jan 27 20:00:01 mythtv mythbackend: mythbackend[1342]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "Victoria on Masterpiece":"Et in Arcadia" on cardid [5] (Tuning => Recording)
Jan 27 20:00:02 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:4192 (TuningNewRecorder) TVRec[5]: rec->GetPathname(): '/var/lib/mythtv/recordings/1121_20190128020000.ts'
Jan 27 20:00:02 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:4224 (TuningNewRecorder) TVRec[5]: TuningNewRecorder - CreateRecorder()
Jan 27 20:00:02 mythtv mythbackend: mythbackend[1342]: E TVRecEvent recorders/recorderbase.cpp:232 (SetStrOption) RecBase[5](103C4AEC-0): SetStrOption(...recordingtype): Option not in profile.
Jan 27 20:04:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 27 20:18:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 27 20:32:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 27 20:46:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 27 20:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 27 20:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787cf8c0) as a client (events: 0)
Jan 27 20:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(81) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787cf8c0) 'mythtv' disconnected
Jan 27 20:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(83) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(55957884a250) disconnected
Jan 27 21:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[5]: Changing from RecordingOnly to None
Jan 27 21:00:00 mythtv mythbackend: mythbackend[1342]: N RecThread recorders/recorderbase.cpp:501 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (1280x720 A/R: 3 59.9401fps) Audio Codec: ac3
Jan 27 21:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent recordinginfo.cpp:1186 (FinishedRecording) Finished recording Victoria on Masterpiece "Et in Arcadia": channel 1121
Jan 27 21:00:00 mythtv mythbackend: mythbackend[1342]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "Victoria on Masterpiece":"Et in Arcadia" on cardid [5] (Recording => Recorded)
Jan 27 21:00:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -3 376 0 UpdateRecStatus2 | Victoria on Masterpiece | Et in Arcadia | Albert enjoys time away from the family but Vict
Jan 27 21:00:02 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 15 items in 0.9 = 0.52 match + 0.14 check + 0.23 place
Jan 27 21:00:02 mythtv mythbackend: mythbackend[1342]: I Scheduler mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Jan 27 21:00:07 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:00:07 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578759a40) as a client (events: 0)
Jan 27 21:00:07 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:00:07 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578802980) as a client (events: 1)
Jan 27 21:00:15 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(51) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578759a40) 'mythtv' disconnected
Jan 27 21:00:15 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578802980) 'mythtv' disconnected
Jan 27 21:00:20 mythtv mythbackend: mythbackend[1342]: I Metadata_6613 jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Victoria on Masterpiece":"Et in Arcadia" recorded from channel 1121 at 2019-01-28T02:00:00Z
Jan 27 21:00:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:00:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957884a250) as a client (events: 0)
Jan 27 21:00:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:00:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787667e0) as a client (events: 1)
Jan 27 21:00:34 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787667e0) 'mythtv' disconnected
Jan 27 21:00:34 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(51) mainserver.cpp:7643 (connectionClosed) Monitor sock(55957884a250) 'mythtv' disconnected
Jan 27 21:00:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 21:01:25 mythtv mythbackend: mythbackend[1342]: I Commflag_6614 jobqueue.cpp:2282 (DoFlagCommercialsThread) JobQueue: Commercial Detection Starting for "Victoria on Masterpiece":"Et in Arcadia" recorded from channel 1121 at 2019-01-28T02:00:00Z
Jan 27 21:01:28 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:01:28 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957876d350) as a client (events: 0)
Jan 27 21:01:28 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:01:28 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787bad50) as a client (events: 1)
Jan 27 21:01:35 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787bad50) 'mythtv' disconnected
Jan 27 21:01:35 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(51) mainserver.cpp:7643 (connectionClosed) Monitor sock(55957876d350) 'mythtv' disconnected
Jan 27 21:01:35 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:01:35 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957876d350) as a client (events: 0)
Jan 27 21:01:35 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:01:35 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787667e0) as a client (events: 1)
Jan 27 21:01:42 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787667e0) 'mythtv' disconnected
Jan 27 21:01:42 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(51) mainserver.cpp:7643 (connectionClosed) Monitor sock(55957876d350) 'mythtv' disconnected
Jan 27 21:15:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 21:31:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 21:39:38 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:39:38 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787bad50) as a client (events: 0)
Jan 27 21:39:38 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:39:38 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578785920) as a client (events: 1)
Jan 27 21:39:45 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(65) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578785920) 'mythtv' disconnected
Jan 27 21:39:45 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(49) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787bad50) 'mythtv' disconnected
Jan 27 21:39:45 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:39:45 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787bad50) as a client (events: 0)
Jan 27 21:39:45 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:39:45 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787cd3b0) as a client (events: 1)
Jan 27 21:39:52 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(65) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787cd3b0) 'mythtv' disconnected
Jan 27 21:39:52 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(49) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787bad50) 'mythtv' disconnected
Jan 27 21:39:59 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:39:59 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787667e0) as a client (events: 0)
Jan 27 21:39:59 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 27 21:39:59 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787bad50) as a client (events: 1)
Jan 27 21:39:59 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(49) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787667e0) 'mythtv' disconnected
Jan 27 21:39:59 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(68) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787bad50) 'mythtv' disconnected
Jan 27 21:46:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 21:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 27 21:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787cd3b0) as a client (events: 0)
Jan 27 21:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787cd3b0) 'mythtv' disconnected
Jan 27 21:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(71) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787ff5d0) disconnected
Jan 27 22:01:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 22:16:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 22:31:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 22:46:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 22:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 27 22:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578784870) as a client (events: 0)
Jan 27 22:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(559578784870) 'mythtv' disconnected
Jan 27 22:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787ecdb0) disconnected
Jan 27 23:01:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 23:16:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 23:31:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 23:46:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 27 23:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 27 23:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787ff5d0) as a client (events: 0)
Jan 27 23:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787ff5d0) 'mythtv' disconnected
Jan 27 23:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(559578784870) disconnected
Jan 28 00:01:40 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
The one I cannot seem to make work is General Hospital. Day before yesterday I deleted the earlier recording rule (which did not display a channel) and replaced it with a new recording rule (which did display a channel). After recording when I looked at the "Media Library/Watch Recordings" page it showed the thumbnail for the episode and the 1/31 date but when I tried to play it, the thumbnail disappeared and nothing happened. I poked around a bit and when I came back the thumbnail was gone.

"Manage Recordings/Previously Recorded" page reports the episode recorded and has a blue bar beside it. The current recording episode has a green bar beside it.

Here's the mythbackend log for General Hospital.

Code: Select all

Jan 31 12:08:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 12:08:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:637 (SendDeleteMessages) Expiring 5673 MB for 1071 at 2019-01-30T17:08:26Z => "Strahan & Sara"
Jan 31 12:08:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2456 (DeleteRecordedFiles) DeleteRecordedFiles - recording id 2037 filename /var/lib/mythtv/livetv/1071_20190130170826.ts
Jan 31 12:08:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2534 (DoDeleteInDB) DoDeleteINDB - recording id 2037 (chanid 1071 at 2019-01-30T17:08:26Z)
Jan 31 12:08:53 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2037.
Jan 31 12:20:54 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2037.
Jan 31 12:23:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 12:38:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 12:38:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:637 (SendDeleteMessages) Expiring 3148 MB for 1071 at 2019-01-30T18:00:01Z => "Newswatch 7 at 12"
Jan 31 12:38:48 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2456 (DeleteRecordedFiles) DeleteRecordedFiles - recording id 2038 filename /var/lib/mythtv/livetv/1071_20190130180001.ts
Jan 31 12:38:48 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2534 (DoDeleteInDB) DoDeleteINDB - recording id 2038 (chanid 1071 at 2019-01-30T18:00:01Z)
Jan 31 12:38:53 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2038.
Jan 31 12:45:33 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2038.
Jan 31 12:54:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 12:59:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for PLACE PrepareToRecord
Jan 31 12:59:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 13 items in 0.1 = 0.00 match + 0.00 check + 0.13 place
Jan 31 12:59:30 mythtv mythbackend: mythbackend[1342]: I Scheduler mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Jan 31 12:59:30 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:1625 (HandlePendingRecordings) TVRec[5]: ASK_RECORDING 5 29 0 0
Jan 31 13:00:00 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Jan 31 13:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[5]: Changing from None to RecordingOnly
Jan 31 13:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Jan 31 13:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:3649 (TuningFrequency) TVRec[5]: TuningFrequency
Jan 31 13:00:00 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2923 (HandleRecordingStatusChange) Tuning recording: "General Hospital": channel 1071 on cardid [5], sourceid 1
Jan 31 13:00:02 mythtv mythbackend: mythbackend[1342]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "General Hospital" on cardid [5] (Tuning => Recording)
Jan 31 13:00:02 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:4192 (TuningNewRecorder) TVRec[5]: rec->GetPathname(): '/var/lib/mythtv/recordings/1071_20190131190000.ts'
Jan 31 13:00:02 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:4224 (TuningNewRecorder) TVRec[5]: TuningNewRecorder - CreateRecorder()
Jan 31 13:00:02 mythtv mythbackend: mythbackend[1342]: E TVRecEvent recorders/recorderbase.cpp:232 (SetStrOption) RecBase[5](103C4AEC-0): SetStrOption(...recordingtype): Option not in profile.
Jan 31 13:06:21 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 13:06:21 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578739060) as a client (events: 0)
Jan 31 13:06:21 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 13:06:21 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578740270) as a client (events: 1)
Jan 31 13:06:21 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(77) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578739060) 'mythtv' disconnected
Jan 31 13:06:21 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(79) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578740270) 'mythtv' disconnected
Jan 31 13:10:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 31 13:10:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:637 (SendDeleteMessages) Expiring 3185 MB for 1071 at 2019-01-30T18:30:01Z => "Daily Blast Live"
Jan 31 13:10:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2456 (DeleteRecordedFiles) DeleteRecordedFiles - recording id 2039 filename /var/lib/mythtv/livetv/1071_20190130183001.ts
Jan 31 13:10:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2534 (DoDeleteInDB) DoDeleteINDB - recording id 2039 (chanid 1071 at 2019-01-30T18:30:01Z)
Jan 31 13:10:53 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2039.
Jan 31 13:16:07 mythtv mythbackend: mythbackend[1342]: I CoreContext housekeeper.cpp:725 (Run) Queueing HouseKeeperTask 'MythFillDB'.
Jan 31 13:16:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 12
Jan 31 13:16:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping housekeeper.cpp:135 (Run) Running HouseKeeperTask 'MythFillDB'.
Jan 31 13:16:14 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 13:16:14 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578781ae0) as a client (events: 0)
Jan 31 13:17:42 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2039.
Jan 31 13:18:18 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - HandleRescheduleRecordings
Jan 31 13:18:19 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(79) mainserver.cpp:7643 (connectionClosed) Playback sock(559578781ae0) 'mythtv' disconnected
Jan 31 13:18:19 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 14 items in 0.8 = 0.55 match + 0.09 check + 0.13 place
Jan 31 13:18:20 mythtv mythbackend: mythbackend[1342]: I SSDP mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 12
Jan 31 13:18:20 mythtv mythbackend: mythbackend[1342]: I HouseKeeping housekeeper.cpp:153 (Run) HouseKeeperTask 'MythFillDB' Finished Successfully.
Jan 31 13:25:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 31 13:39:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 31 13:53:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 202.0 GB w/freq: 14 min
Jan 31 14:00:00 mythtv mythbackend: mythbackend[1342]: I TVRecEvent tv_rec.cpp:1088 (HandleStateChange) TVRec[5]: Changing from RecordingOnly to None
Jan 31 14:00:01 mythtv mythbackend: mythbackend[1342]: N RecThread recorders/recorderbase.cpp:501 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (1920x1080 A/R: 3 29.97fps) Audio Codec: ac3
Jan 31 14:00:02 mythtv mythbackend: mythbackend[1342]: I TVRecEvent recordinginfo.cpp:1186 (FinishedRecording) Finished recording General Hospital: channel 1071
Jan 31 14:00:02 mythtv mythbackend: mythbackend[1342]: E CoreContext mainserver.cpp:1172 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
Jan 31 14:00:02 mythtv mythbackend: mythbackend[1342]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "General Hospital" on cardid [5] (Recording => Recorded)
Jan 31 14:00:02 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -3 396 0 UpdateRecStatus2 | General Hospital |  | Julian wants to clear the air; Drew is flustered; Al
Jan 31 14:00:03 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 13 items in 0.7 = 0.30 match + 0.08 check + 0.36 place
Jan 31 14:00:09 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:00:09 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787b7b50) as a client (events: 0)
Jan 31 14:00:09 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:00:09 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578781ae0) as a client (events: 1)
Jan 31 14:00:12 mythtv mythbackend: mythbackend[1342]: I Metadata_6621 jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "General Hospital" recorded from channel 1071 at 2019-01-31T19:00:00Z
Jan 31 14:00:18 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:00:18 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787ff180) as a client (events: 0)
Jan 31 14:00:18 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:00:18 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787e7970) as a client (events: 1)
Jan 31 14:00:25 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787b7b50) 'mythtv' disconnected
Jan 31 14:00:25 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578781ae0) 'mythtv' disconnected
Jan 31 14:00:28 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(72) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787ff180) 'mythtv' disconnected
Jan 31 14:00:28 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(81) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787e7970) 'mythtv' disconnected
Jan 31 14:01:17 mythtv mythbackend: mythbackend[1342]: I Commflag_6622 jobqueue.cpp:2282 (DoFlagCommercialsThread) JobQueue: Commercial Detection Starting for "General Hospital" recorded from channel 1071 at 2019-01-31T19:00:00Z
Jan 31 14:01:20 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:01:20 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787baef0) as a client (events: 0)
Jan 31 14:01:20 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:01:20 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578781ae0) as a client (events: 1)
Jan 31 14:01:27 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(68) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578781ae0) 'mythtv' disconnected
Jan 31 14:01:27 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(51) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787baef0) 'mythtv' disconnected
Jan 31 14:01:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:01:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787baef0) as a client (events: 0)
Jan 31 14:01:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:01:27 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578739060) as a client (events: 1)
Jan 31 14:01:34 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(68) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578739060) 'mythtv' disconnected
Jan 31 14:01:34 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(51) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787baef0) 'mythtv' disconnected
Jan 31 14:07:45 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 14:07:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:637 (SendDeleteMessages) Expiring 6385 MB for 1071 at 2019-01-30T19:00:01Z => "General Hospital"
Jan 31 14:07:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2456 (DeleteRecordedFiles) DeleteRecordedFiles - recording id 2041 filename /var/lib/mythtv/livetv/1071_20190130190001.ts
Jan 31 14:07:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2534 (DoDeleteInDB) DoDeleteINDB - recording id 2041 (chanid 1071 at 2019-01-30T19:00:01Z)
Jan 31 14:07:54 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2041.
Jan 31 14:20:06 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:06 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787add30) as a client (events: 0)
Jan 31 14:20:06 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:06 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578781ae0) as a client (events: 1)
Jan 31 14:20:13 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(70) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578781ae0) 'mythtv' disconnected
Jan 31 14:20:13 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(61) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787add30) 'mythtv' disconnected
Jan 31 14:20:13 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:13 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787add30) as a client (events: 0)
Jan 31 14:20:13 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:13 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957883c750) as a client (events: 1)
Jan 31 14:20:20 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(70) mainserver.cpp:7643 (connectionClosed) Monitor sock(55957883c750) 'mythtv' disconnected
Jan 31 14:20:20 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(61) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787add30) 'mythtv' disconnected
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578739060) as a client (events: 0)
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787a4040) as a client (events: 1)
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578767770) as a client (events: 0)
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jan 31 14:20:25 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(559578713040) as a client (events: 1)
Jan 31 14:20:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578739060) 'mythtv' disconnected
Jan 31 14:20:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(74) mainserver.cpp:7643 (connectionClosed) Monitor sock(5595787a4040) 'mythtv' disconnected
Jan 31 14:20:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(77) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578767770) 'mythtv' disconnected
Jan 31 14:20:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(79) mainserver.cpp:7643 (connectionClosed) Monitor sock(559578713040) 'mythtv' disconnected
Jan 31 14:20:32 mythtv mythbackend: mythbackend[1342]: E Commflag_6622 previewgenerator.cpp:275 (Run) Preview: Encountered problems running '/usr/bin/mythpreviewgen --size 0x0 --chanid 1071 --starttime 20190131190000' - (128)
Jan 31 14:21:29 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2041.
Jan 31 14:23:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 14:23:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:637 (SendDeleteMessages) Expiring 6545 MB for 1071 at 2019-01-31T19:00:00Z => "General Hospital"
Jan 31 14:23:46 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK 0 396 0 DoHandleDelete3 | General Hospital |  | Julian wants to clear the air; Drew is flustered; Alex
Jan 31 14:23:46 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -3 396 0 ForgetHistory | General Hospital |  | Julian wants to clear the air; Drew is flustered; Alexi
Jan 31 14:23:46 mythtv mythbackend: mythbackend[1342]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 13 items in 0.2 = 0.04 match + 0.02 check + 0.10 place
Jan 31 14:23:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2456 (DeleteRecordedFiles) DeleteRecordedFiles - recording id 2042 filename /var/lib/mythtv/recordings/1071_20190131190000.ts
Jan 31 14:23:49 mythtv mythbackend: mythbackend[1342]: N DeleteThread mainserver.cpp:2534 (DoDeleteInDB) DoDeleteINDB - recording id 2042 (chanid 1071 at 2019-01-31T19:00:00Z)
Jan 31 14:23:53 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2042.
Jan 31 14:37:43 mythtv mythbackend: mythbackend[1342]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 2042.
Jan 31 14:39:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 14:54:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 15:09:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 15:24:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 15:38:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 9
Jan 31 15:38:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping housekeeper.cpp:135 (Run) Running HouseKeeperTask 'UpdateRadioStreams'.
Jan 31 15:38:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping backendhousekeeper.cpp:496 (DoRun) Performing Radio Streams Update: /usr/bin/mythutil --updateradiostreams  --verbose general --loglevel info --quiet --syslog local7
Jan 31 15:38:10 mythtv mythbackend: mythbackend[1342]: E HouseKeeping backendhousekeeper.cpp:509 (DoRun) Update Radio Streams command '/usr/bin/mythutil' failed
Jan 31 15:38:10 mythtv mythbackend: mythbackend[1342]: I HouseKeeping housekeeper.cpp:149 (Run) HouseKeeperTask 'UpdateRadioStreams' Failed.
Jan 31 15:39:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 15:54:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 16:09:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 16:24:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 16:39:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 16:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 16:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787be870) as a client (events: 0)
Jan 31 16:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787be870) 'mythtv' disconnected
Jan 31 16:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787a4040) disconnected
Jan 31 16:54:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 17:09:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 17:17:08 mythtv mythbackend: mythbackend[1342]: I CoreContext housekeeper.cpp:725 (Run) Queueing HouseKeeperTask 'ThemeUpdateNotifications'.
Jan 31 17:17:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping mythdbcon.cpp:435 (PurgeIdleConnections) New DB connection, total: 8
Jan 31 17:17:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping housekeeper.cpp:135 (Run) Running HouseKeeperTask 'ThemeUpdateNotifications'.
Jan 31 17:17:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping backendhousekeeper.cpp:397 (DoRun) Loading themes for 29
Jan 31 17:17:08 mythtv mythbackend: mythbackend[1342]: I HouseKeeping backendhousekeeper.cpp:411 (DoRun) Loading themes for 29.1
Jan 31 17:17:09 mythtv mythbackend: mythbackend[1342]: I HouseKeeping backendhousekeeper.cpp:450 (LoadVersion) HouseKeeper: Failed to download http://themes.mythtv.org/themes/repository/29.1/themes.zip remote themes info package.
Jan 31 17:17:09 mythtv mythbackend: mythbackend[1342]: I HouseKeeping housekeeper.cpp:153 (Run) HouseKeeperTask 'ThemeUpdateNotifications' Finished Successfully.
Jan 31 17:24:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 17:39:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 17:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 17:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787add30) as a client (events: 0)
Jan 31 17:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787add30) 'mythtv' disconnected
Jan 31 17:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(559578792830) disconnected
Jan 31 17:54:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 18:09:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 18:24:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 18:39:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 18:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 18:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787ff180) as a client (events: 0)
Jan 31 18:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787ff180) 'mythtv' disconnected
Jan 31 18:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787e9d40) disconnected
Jan 31 18:54:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 19:10:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 19:26:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 19:41:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 19:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 19:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787cb260) as a client (events: 0)
Jan 31 19:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(70) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787cb260) 'mythtv' disconnected
Jan 31 19:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(73) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787ff180) disconnected
Jan 31 19:56:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 20:11:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 20:26:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 20:41:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 20:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 20:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957877f070) as a client (events: 0)
Jan 31 20:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(71) mainserver.cpp:7643 (connectionClosed) Playback sock(55957877f070) 'mythtv' disconnected
Jan 31 20:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(49) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(559578794cc0) disconnected
Jan 31 20:56:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 21:12:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 21:27:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 21:42:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 21:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 21:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595787be870) as a client (events: 0)
Jan 31 21:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595787be870) 'mythtv' disconnected
Jan 31 21:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787add30) disconnected
Jan 31 21:58:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 22:13:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 22:28:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 22:43:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 22:47:30 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 22:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(5595788703e0) as a client (events: 0)
Jan 31 22:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(67) mainserver.cpp:7643 (connectionClosed) Playback sock(5595788703e0) 'mythtv' disconnected
Jan 31 22:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(69) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787cb260) disconnected
Jan 31 22:58:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 23:13:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 23:28:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 23:44:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Jan 31 23:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Jan 31 23:47:31 mythtv mythbackend: mythbackend[1342]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: mythtv(55957877a630) as a client (events: 0)
Jan 31 23:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(68) mainserver.cpp:7643 (connectionClosed) Playback sock(55957877a630) 'mythtv' disconnected
Jan 31 23:47:31 mythtv mythbackend: mythbackend[1342]: I MythSocketThread(70) mainserver.cpp:7681 (connectionClosed) FileTransfer sock(5595787bc960) disconnected
Jan 31 23:59:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Feb  1 00:14:46 mythtv mythbackend: mythbackend[1342]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 200.0 GB w/freq: 15 min
Any insights will be greatly appreciated.

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by pgreenwood » Sun Feb 03, 2019 4:14 pm

This "E" message appears in the logs for [failed] recording General Hospital

Code: Select all

E CoreContext mainserver.cpp:1172 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
This line does not appear in the [successful] recording of Victoria.

What does this mean?

Also, I can't seem to find an explanation for what the other flags "N" "C" and "I" mean. Can someone enlighten me? Thanks.

wesnewell
Senior
Posts: 419
Joined: Mon Jun 23, 2014 6:54 pm
Location: Wylie TX, USA
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by wesnewell » Sun Feb 03, 2019 7:35 pm

pgreenwood wrote:
Tue Dec 04, 2018 11:44 am
What if I just did a complete reinstall. Can old recordings be imported into the recordings directory and have them added to the database so it knows they're there?
The simplest method I found was just put them in your new videos dir. You can then watch them from there.
BE/FE-Asrock AB350 Pro AMD Ryzen 3 2200G, 6 atsc tuners. Frontends-GF8200's,,AMD Athlon II's. Mythtv user since 2005.

wesnewell
Senior
Posts: 419
Joined: Mon Jun 23, 2014 6:54 pm
Location: Wylie TX, USA
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by wesnewell » Sun Feb 03, 2019 7:45 pm

pgreenwood wrote:
Sun Feb 03, 2019 4:14 pm
This "E" message appears in the logs for [failed] recording General Hospital

Code: Select all

E CoreContext mainserver.cpp:1172 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
This line does not appear in the [successful] recording of Victoria.

What does this mean?

Also, I can't seem to find an explanation for what the other flags "N" "C" and "I" mean. Can someone enlighten me? Thanks.
I have no idea, but I get the same thing after every recording. So I wouldn't worry about it.
BE/FE-Asrock AB350 Pro AMD Ryzen 3 2200G, 6 atsc tuners. Frontends-GF8200's,,AMD Athlon II's. Mythtv user since 2005.

User avatar
bill6502
Developer
Posts: 1152
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by bill6502 » Sun Feb 03, 2019 9:30 pm

The single caps in the logs are the 1st letters of the loglevel, except emerg which
is a '!' Try: mythbackend --help loglevel.

Code: Select all

Type:        QString
Default:     info
Description: Set the logging level.  All log messages at lower levels will be discarded.
             In descending order: emerg, alert, crit, err, warning, notice, info, debug
             defaults to info

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by pgreenwood » Wed Feb 06, 2019 8:46 pm

UPDATE - I got, and ran, find_orphans.py several times until it was down to a persistent few files that just would not go away. Upon restarting the frontend I was advised an "updated database was required (schema is 5000 versions behind). Please run mythtv-setup or mythbackend to update your database".

I did that and was warned ERROR 1040(08004): Too many connections
Status: Name "com.ubuntu.Upstart" does not exist.

I ran through the backend setup screens. All my information appeared correct. I didn't change anything. Ran mythfilldatabase as prompted. General Hospital was scheduled to record about an hour later. Everything appeared in order for the recording.

The show recorded as expected and remains in the directory of recordings (did not get deleted after recording). Fingers crossed but it appears find_orphans.py cleared out a lot of the cruft and I won't have to do a complete reinstallation. My hypothesis is the database included so many General Hospital entries it thought it had everything and deleted any new ones.

I will give it a couple days and then mark it SOLVED.

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by pgreenwood » Thu Feb 07, 2019 2:42 pm

GH recording of 2/6/19, there yesterday, is gone today. I will likely remove and reinstall.

User avatar
bill6502
Developer
Posts: 1152
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: 18.04 0.29 scheduled recording fails

Post by bill6502 » Thu Feb 07, 2019 9:15 pm

See if the recording is being Auto Expired. You can check the GH recording rule
and turn if off there. No idea how much space you've got on your recording
drive, but the above logs show MythTV is expecting 200GB spare.

pgreenwood
Newcomer
Posts: 12
Joined: Tue Oct 25, 2016 4:27 pm
United States of America

[SOLVED] 18.04 0.29 scheduled recording fails

Post by pgreenwood » Fri Feb 08, 2019 10:17 pm

I believe that is/was the problem -- recording share full. I had too many marked "do not delete". I changed that flag on a bunch of titles and now at least yesterday's GH recording survived overnight without being deleted. I'm going to assume that's the final answer and thank you for your help!

Post Reply