[mythtv-users] live TV plays, recordings fail

Sat Nov 2 18:13:58 UTC 2019

On Sat, Nov 2, 2019 at 11:02 AM Daryl McDonald <darylangela at gmail.com>
wrote:

>
>
> On Sat, Nov 2, 2019 at 10:10 AM Bill Meek <keemllib at gmail.com> wrote:
>
>> On 11/2/19 8:56 AM, Daryl McDonald wrote:
>>
>> > this is what the pastebin reports without the space:
>> >   mythbackend.log doesn't appear to be installed, or isn't in your
>> PATH, aborting.
>>
>> The command is: ./shortlog.sh  mythbackend
>>
>> The error message suggests you entered ./shortlog.sh  mythbackend.log
>>
>> Try running it without the pipe to pastebinit to see what you get.
>>
>> --
>> Bill
>> _____
>>
>  daryl at trieli:~$ ./shortlog.sh mythbackend
>
> ***** Total ines in logfile: 29999 /var/log/mythtv/mythbackend.log
>
> ***** Starting timestamp = 'Binary file /var/'
>
> sed: -e expression #1, char 23: expected newer version of sed
> daryl at trieli:~$ ./shortlog.sh mythbackend | pastebinit
> sed: -e expression #1, char 23: expected newer version of sed
> http://paste.ubuntu.com/p/G2pJmKrpm6/
> daryl at trieli:~$
> and from the pastebin:
> ***** Total ines in logfile: 29999 /var/log/mythtv/mythbackend.log
>
> ***** Starting timestamp = 'Binary file /var/'
>

Part of the log from around 10pm when a rcording failed:
 Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I TVRecEvent
tv_rec.cpp:3665 (TuningFrequency) TVRec[1]: TuningFrequency
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E TVRecEvent
dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams --
Unknown tuner type = 0xffffffff80000000
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E TVRecEvent
recorders/dtvchannel.cpp:300 (SetChannelByString) DTVChan[1](10426B6B-0):
SetChannelByString(62_1): Failed to initialize multiplex options
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E TVRecEvent
tv_rec.cpp:3743 (TuningFrequency) TVRec[1]: Failed to set channel to 62_1.
Reverting to kState_None
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I TVRecEvent
tv_rec.cpp:1090 (HandleStateChange) TVRec[1]: Changing from RecordingOnly
to None
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I CoreContext
scheduler.cpp:734 (UpdateRecStatus) Updating status for "Blue
Bloods":"Glass Houses" on cardid [1] (Pending => Recorder Failed)
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I Scheduler
scheduler.cpp:2923 (HandleRecordingStatusChange) Tuning recording: "Blue
Bloods":"Glass Houses": channel 1621 on cardid [1], sourceid 1
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I Scheduler
scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -9
1212 0 UpdateRecStatus2 | Blue Bloods | Glass Houses | Frank is conflicted
about how to deal with a former cop's gripe about his pension; Jamie helps
a friend deal with illicit pictures of his daughter on the internet;
Anthony confronts the realization that his foster son isn't suited for
police work. | EP012801050205
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E Scheduler
recordinginfo.cpp:1033 (InsertProgram)
RecordingInfo::InsertProgram(ProgramInfo(1621_20191102015900.ts):
channame(WWJ-HD) startts(Sat Nov 2 02:00:00 2019 GMT) endts(Sat Nov 2
03:00:00 2019 GMT)#012             recstartts(Sat Nov 2 01:59:00 2019 GMT)
recendts(Sat Nov 2 03:00:00 2019 GMT)#012             title(Blue Bloods)):
recording already exists...
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I TVRecEvent
tv_rec.cpp:1090 (HandleStateChange) TVRec[1]: Changing from None to
RecordingOnly
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I TVRecEvent
tv_rec.cpp:3665 (TuningFrequency) TVRec[1]: TuningFrequency
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E TVRecEvent
dtvmultiplex.cpp:379 (ParseTuningParams) DTVMux: ParseTuningParams --
Unknown tuner type = 0xffffffff80000000
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E TVRecEvent
recorders/dtvchannel.cpp:300 (SetChannelByString) DTVChan[1](10426B6B-0):
SetChannelByString(62_1): Failed to initialize multiplex options
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E TVRecEvent
tv_rec.cpp:3743 (TuningFrequency) TVRec[1]: Failed to set channel to 62_1.
Reverting to kState_None
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I TVRecEvent
tv_rec.cpp:1090 (HandleStateChange) TVRec[1]: Changing from RecordingOnly
to None
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I CoreContext
scheduler.cpp:734 (UpdateRecStatus) Updating status for "Blue
Bloods":"Glass Houses" on cardid [1] (Tuning => Recorder Failed)
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: E Scheduler
scheduler.cpp:797 (ChangeRecordingEnd) Failed to change end time on card 1
to 2019-11-02T03:00:00Z
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: C CoreContext
programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded
entry for 0.
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I Scheduler
scheduler.cpp:2460 (HandleReschedule) Reschedule interrupted, will retry
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I Scheduler
scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -9
1212 0 UpdateRecStatus2 | Blue Bloods | Glass Houses | Frank is conflicted
about how to deal with a former cop's gripe about his pension; Jamie helps
a friend deal with illicit pictures of his daughter on the internet;
Anthony confronts the realization that his foster son isn't suited for
police work. | EP012801050205
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I Scheduler
scheduler.cpp:2356 (HandleReschedule) Reschedule requested for PLACE
Interrupted
Nov  1 21:59:00 trieli mythbackend: mythbackend[1111]: I Scheduler
scheduler.cpp:2469 (HandleReschedule) Scheduled 185 items in 0.2 = 0.00
match + 0.00 check + 0.15 place
Nov  1 21:59:31 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015900.ts):
GetPlaybackURL: '1621_20191102015900.ts' should be local, but it can not be
found.
Nov  1 21:59:31 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015900.ts):
GetPlaybackURL: '1621_20191102015900.ts' should be local, but it can not be
found.
Nov  1 21:59:31 trieli mythbackend: mythbackend[1111]: I Metadata_20235
jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup
Starting for "Blue Bloods":"Glass Houses" recorded from channel 1621 at
2019-11-02T01:59:00Z
Nov  1 21:59:34 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Nov  1 21:59:34 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1743 (HandleAnnounce) MainServer: adding:
trieli(560db0470b80) as a client (events: 0)
Nov  1 21:59:34 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov  1 21:59:34 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1743 (HandleAnnounce) MainServer: adding:
trieli(560db0466420) as a client (events: 1)
Nov  1 21:59:45 trieli mythbackend: mythbackend[1111]: I
MythSocketThread(78) mainserver.cpp:7674 (connectionClosed) Playback
sock(560db0470b80) 'trieli' disconnected
Nov  1 21:59:45 trieli mythbackend: mythbackend[1111]: I
MythSocketThread(80) mainserver.cpp:7674 (connectionClosed) Monitor
sock(560db0466420) 'trieli' disconnected
Nov  1 21:59:59 trieli mythbackend: mythbackend[1111]: N Expire
autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free
Space: 10.0 GB w/freq: 15 min
Nov  1 22:00:00 trieli mythbackend: mythbackend[1111]: N Scheduler
scheduler.cpp:3269 (HandleIdleShutdown) Blocking shutdown because of a
connected client
Nov  1 22:00:36 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015900.ts):
GetPlaybackURL: '1621_20191102015900.ts' should be local, but it can not be
found.
Nov  1 22:00:36 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015900.ts):
GetPlaybackURL: '1621_20191102015900.ts' should be local, but it can not be
found.
Nov  1 22:00:36 trieli mythbackend: mythbackend[1111]: I Commflag_20236
jobqueue.cpp:2282 (DoFlagCommercialsThread) JobQueue: Commercial Detection
Starting for "Blue Bloods":"Glass Houses" recorded from channel 1621 at
2019-11-02T01:59:00Z
Nov  1 22:00:38 trieli mythbackend: mythbackend[1111]: E Commflag_20236
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015900.ts):
GetPlaybackURL: '1621_20191102015900.ts' should be local, but it can not be
found.
Nov  1 22:00:41 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015901.ts):
GetPlaybackURL: '1621_20191102015901.ts' should be local, but it can not be
found.
Nov  1 22:00:41 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015901.ts):
GetPlaybackURL: '1621_20191102015901.ts' should be local, but it can not be
found.
Nov  1 22:00:41 trieli mythbackend: mythbackend[1111]: I Metadata_20237
jobqueue.cpp:2157 (DoMetadataLookupThread) JobQueue: Metadata Lookup
Starting for "Blue Bloods":"Glass Houses" recorded from channel 1621 at
2019-11-02T01:59:01Z
Nov  1 22:00:44 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Playback
Nov  1 22:00:44 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1743 (HandleAnnounce) MainServer: adding:
trieli(560db0470f30) as a client (events: 0)
Nov  1 22:00:44 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Nov  1 22:00:44 trieli mythbackend: mythbackend[1111]: I ProcessRequest
mainserver.cpp:1743 (HandleAnnounce) MainServer: adding:
trieli(560db043dca0) as a client (events: 1)
Nov  1 22:00:53 trieli mythbackend: mythbackend[1111]: I
MythSocketThread(78) mainserver.cpp:7674 (connectionClosed) Playback
sock(560db0470f30) 'trieli' disconnected
Nov  1 22:00:53 trieli mythbackend: mythbackend[1111]: I
MythSocketThread(80) mainserver.cpp:7674 (connectionClosed) Monitor
sock(560db043dca0) 'trieli' disconnected
Nov  1 22:01:46 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015901.ts):
GetPlaybackURL: '1621_20191102015901.ts' should be local, but it can not be
found.
Nov  1 22:01:46 trieli mythbackend: mythbackend[1111]: E JobQueue
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015901.ts):
GetPlaybackURL: '1621_20191102015901.ts' should be local, but it can not be
found.
Nov  1 22:01:46 trieli mythbackend: mythbackend[1111]: I Commflag_20238
jobqueue.cpp:2282 (DoFlagCommercialsThread) JobQueue: Commercial Detection
Starting for "Blue Bloods":"Glass Houses" recorded from channel 1621 at
2019-11-02T01:59:01Z
Nov  1 22:01:48 trieli mythbackend: mythbackend[1111]: E Commflag_20238
programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1621_20191102015901.ts):
GetPlaybackURL: '1621_20191102015901.ts' should be local, but it can not be
found.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20191102/23d91eee/attachment-0001.htm>