All Recordings marked Damaged

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

Moderator: Forum Moderators

Post Reply
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

All Recordings marked Damaged

Post by pblarry49 »

I don't know where to begin. Since 24JUN19 Every one of my recordings are being flagged as damaged. The files play back just fine with the exception there are no commercial flags and because they are damaged, Myth keeps recording extra copies if it should rerun. When I run Mythcommflag against one of the files it intermittently errors on "Unrecognized Frames" or decoding errors as below:
mythcommflag.log entries:

Code: Select all

Jun 26 02:12:45 hms-server2 mythcommflag: mythcommflag[10858]: E Decoder avformatdecoder.cpp:3570 (ProcessVideoPacket) AFD: Unknown decoding error
Jun 26 02:13:21 hms-server2 mythcommflag: mythcommflag[10858]: E Decoder avformatdecoder.cpp:3570 (ProcessVideoPacket) AFD: Unknown decoding error
Mythbackend.log doesn't seem to have anything that wasn't there prior to 24JUN.
Any help, information and/or solutions would be most appreciated.

Mythtv backend runs on a QNAP TS-453A, 16GB RAM with about 7TB of available RAID 5 storage. This arrangement has been working for at least 3 years with no trouble at all. The only changes have been updates/upgrades as they have been released. We record everything except sports that we want to see live. I'm using OSMC on a pi3 for a front end. All the interesting network connections are over an Ethernet gigabit LAN. My tuners are HDHomeruns, one Prime and one Duo.

My versions are:
"Linux Station" Data:
Linux hms-server2 4.14.24-qnap #1 SMP Wed Jun 26 09:57:22 CST 2019 x86_64 x86_64 x86_64 GNU/Linux
Description: Ubuntu 16.04.6 LTS
MythTV Version : v29.1
Network Protocol : 91
Library API : 29.20180316-1
QT Version : 5.5.1
QNAP Firmware: 4.4.1.0978

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

Re: All Recordings marked Damaged

Post by bill6502 »

Hi,

Very odd (that there's nothing related in the backend log.) There should be lines like this:

Code: Select all

2019-05-18 22:20:29.076614 I [21122/21130] TVRecEvent tv_rec.cpp:826 (FinishedRecording) - TVRec[26]: FinishedRecording(1051_2019-05-19T03:05:15Z) damaged recq:<RecordingQuality overall_score="0" key="1051_2019-05-19T03:05:15Z" countinuity_error_count="0" packet_count="5221542">
    <Gap start="2019-05-19T03:00:00Z" end="2019-05-19T03:05:14Z" duration="314" />
    <Gap start="2019-05-19T03:20:25Z" end="2019-05-19T03:29:00Z" duration="514" />
</RecordingQuality>
where the duration is the number of seconds lost. Or there may be other errors that cause the
recording to be flagged. You can start the backend with -v record, or with a running backend type:
mythbackend --setverbose record. Or, even use record:debug.
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

Thanks for the quick reply. Your answer made me go back and examine a bit further.
I recorded the NBC news as a test and this is how it finished:

mythbackend.log:

Code: Select all

Jul  2 19:00:01 hms-server2 mythbackend: mythbackend[3974]: N RecThread recorders/recorderbase.cpp:501 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (1920x1080 A/R: 3 29.97fps) Audio Codec: ac3
Jul  2 19:00:01 hms-server2 mythbackend: mythbackend[3974]: I TVRecEvent tv_rec.cpp:863 (FinishedRecording) TVRec[1]:
	 FinishedRecording(2081_2019-07-02T22:30:00Z) damaged recq:
	 <RecordingQuality overall_score="0.9" key="2081_2019-07-02T22:30:00Z" countinuity_error_count="2904" packet_count="16333386" />
Jul  2 19:00:01 hms-server2 mythbackend: mythbackend[3974]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "NBC Nightly News With Lester Holt" on cardid [1] (Recording => Recorder Failed)
Jul  2 19:00:01 hms-server2 mythbackend: mythbackend[3974]: I Scheduler scheduler.cpp:2356 (HandleReschedule) 
	Reschedule requested for CHECK -9 25 0 UpdateRecStatus2 | NBC Nightly News With Lester Holt |  |  | EP023034511307
Jul  2 19:00:01 hms-server2 mythbackend: mythbackend[3974]: I Scheduler scheduler.cpp:2469 (HandleReschedule) 
	Scheduled 96 items in 0.1 = 0.00 match + 0.03 check + 0.10 place
unfortunately, I'm not too sure what it means (other than it's bad). Looking further I found a number of lines that are indicating my latest QNAP update may have messed with the file system. The WRITE lines mostly look like this:

Code: Select all

Jul  2 18:42:04 hms-server2 mythbackend: mythbackend[3974]: W TFWWrite threadedfilewriter.cpp:571 (DiskLoop)
	TFW(/nas_share/Multimedia/mythsrv/mythvideo/2081_20190702223000.ts:81): 
	write(65424) cnt 33 total 2034912 -- took a long time, 1117 ms
So, any thoughts?
User avatar
bill6502
Developer
Posts: 2307
Joined: Fri Feb 07, 2014 5:28 pm
United States of America

Re: All Recordings marked Damaged

Post by bill6502 »

Looks like two issues. Any write that takes more than one second causes
the TFW warning to log. The HDHR shouldn't be creating more data than
a GB link and working NAS can handle. I'd try checking both with other (non
MythTV tools.) E.g. write massive files over your network, look for disk errors
on the NAS. If it's running smartd check for errors etc.

I would repeat your test after running: mythbackend --setverbose record on the
backend. The additional logging will (should) show if it's a PID or A/V continuity
error. Although, I'm leaning toward an HDHR issue. Do both the Prime and Duo
fail?

Although not required, I run my HDHRs on a separate NIC. On the other hand, I
don't record to a NAS. In other words, I can't duplicate your setup. You could create
a new Storage Group, create a single recording rule using it and make the Storage
Directory on your backend, just to remove the NAS from the mix.

Be careful of the size of you logs!
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

Thank you very much. I have to mind some family business tonight, but will try these things first thing in the morning.
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

I really appreciate your help with this . I'm not really a newbie, just a really baffled old guy!

Here is the portion of the log of a 30 min recording; logging set to 'verbose record'. This was using a USB attached external drive. the recording was marked damaged. I do see numerous discontinuity errors. I've checked the file system with these results:
  • Name: Disk 1
    Path: /dev/sda
    Performance: 162.58 MB/sec

    Name: Disk 2
    Path: /dev/sdb
    Performance: 177.57 MB/sec

    Name: Disk 3
    Path: /dev/sdc
    Performance: 164.77 MB/sec

    Name: Disk 4
    Path: /dev/sdd
    Performance: 164.99 MB/sec
I've attached the dump of my SMART values.

I am going to contact QNAP support, but I don't think I'll get much help there.

Code: Select all

Jul  3 15:10:42 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatagrabber.cpp:453 (RunGrabber) Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -N Born to Explore With Richard Wiese Morocco: Secret Village
Jul  3 15:10:45 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatagrabber.cpp:453 (RunGrabber) Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M Born to Explore With Richard Wiese
Jul  3 15:10:47 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatagrabber.cpp:453 (RunGrabber) Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M Born to Explore With Richard Wiese
Jul  3 15:10:47 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatadownload.cpp:187 (run) Metadata Lookup Failed: No Results Born to Explore With Richard Wiese 0 0
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(cc22c0) as a client (events: 0)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for MATCH 318 0 0 - PHP
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(cc22c0) 'hms-server2' disconnected
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(cc22c0) as a client (events: 2)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 97 items in 0.2 = 0.08 match + 0.00 check + 0.08 place
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:319 (RecordPending) TVRec[5]: RecordPending on inputid 5
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler cardutil.cpp:1448 (GetConflictingInputs) CardUtil: GetConflictingInputs() input 5
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:433 (StartRecording) TVRec[5]: StartRecording("Born to Explore With Richard Wiese":"Morocco: Secret Village")
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:730 (SetRecordingStatus) TVRec[5]: SetRecordingStatus(Unknown->Aborted) on line 439
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:829 (StartedRecording) TVRec[5]: StartedRecording(1472_2019-07-03T19:11:00Z) fn(/home/llamont/mythtest/1472_20190703191100.ts)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:4135 (LoadProfile) TVRec[5]: Using profile 'Default' to record
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:629
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler tv_rec.cpp:730 (SetRecordingStatus) TVRec[5]: SetRecordingStatus(Aborted->Tuning) on line 632
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:1090 (HandleStateChange) TVRec[5]: Changing from None to RecordingOnly
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @ tv_rec.cpp:1322
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3506 (HandleTuning) TVRec[5]: HandleTuning Request: Program(ProgramInfo(1472_20190703191100.ts): channame(WEDUDT6 (WEDU-DT6)) startts(Wed Jul 3 19:00:00 2019 GMT) endts(Wed Jul 3 19:30:00 2019 GMT)#012             recstartts(Wed Jul 3 19:11:00 2019 GMT) recendts(Wed Jul 3 19:30:00 2019 GMT)#012             title(Born to Explore With Richard Wiese)) channel() input() flags(Recording,)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3575 (TuningShutdowns) TVRec[5]: TuningShutdowns(Program(ProgramInfo(1472_20190703191100.ts): channame(WEDUDT6 (WEDU-DT6)) startts(Wed Jul 3 19:00:00 2019 GMT) endts(Wed Jul 3 19:30:00 2019 GMT)#012             recstartts(Wed Jul 3 19:11:00 2019 GMT) recendts(Wed Jul 3 19:30:00 2019 GMT)#012             title(Born to Explore With Richard Wiese)) channel(472) input(MPEG2TS) flags(Recording,))
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop, @ tv_rec.cpp:3643
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3528 (HandleTuning) TVRec[5]: No recorder yet, calling TuningFrequency
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3665 (TuningFrequency) TVRec[5]: TuningFrequency
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent cardutil.cpp:1448 (GetConflictingInputs) CardUtil: GetConflictingInputs() input 5
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/hdhrstreamhandler.cpp:548 (TuneVChannel) HDHRSH(1313B106-0): TuneVChannel(472) from (none)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/hdhrstreamhandler.cpp:551 (TuneVChannel) HDHRSH(1313B106-0): Tuning vchannel 472
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3792 (TuningFrequency) TVRec[5]: Starting Signal Monitor
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:2051 (SetupSignalMonitor) TVRec[5]: SetupSignalMonitor(1, 0)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/hdhrstreamhandler.cpp:56 (Get) HDHRSH: Using existing stream handler 1313B106-0 for 1313B106-0 (2 in use)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:2074 (SetupSignalMonitor) TVRec[5]: Signal monitor successfully created
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:1871 (SetupDTVSignalMonitor) TVRec[5]: Setting up table monitoring.
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4135 (LoadProfile) TVRec[5]: Using profile 'Live TV' to record
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:1985 (SetupDTVSignalMonitor) TVRec[5]: MPEG program number: 0
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent mpeg/mpegstreamdata.cpp:90 (SetDesiredProgram) MPEGStream[5](0x7f0624194450): SetDesiredProgram(0)
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:2006 (SetupDTVSignalMonitor) TVRec[5]: Successfully set up MPEG table monitoring.
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4410 (SetFlags) TVRec[5]: SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3819
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3820
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4410 (SetFlags) TVRec[5]: SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3825
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3886
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4410 (SetFlags) TVRec[5]: SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @ tv_rec.cpp:3888
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4006 (TuningSignalCheck) TVRec[5]: TuningSignalCheck: Still waiting.  Will timeout @ 15:30:00.000
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I Scheduler scheduler.cpp:2923 (HandleRecordingStatusChange) Tuning recording: "Born to Explore With Richard Wiese":"Morocco: Secret Village": channel 1472 on cardid [5], sourceid 1
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(cc22c0) 'hms-server2' disconnected
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: C CoreContext programinfo.cpp:340 (ProgramInfo) ProgramInfo(): Failed to find recorded entry for 0.
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:21 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(cc70a0) as a client (events: 0)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I SignalMonitor recorders/streamhandler.cpp:81 (AddListener) SH(1313B106-0): AddListener(0x7f0624194450) -- begin
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I SignalMonitor recorders/streamhandler.cpp:93 (AddListener) SH(1313B106-0): AddListener(0x7f0624194450) -- locked
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(cc70a0) 'hms-server2' disconnected
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:145 (run) HDHRSH(1313B106-0): RunTS(): begin
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I SignalMonitor recorders/streamhandler.cpp:115 (AddListener) SH(1313B106-0): AddListener(0x7f0624194450) -- end
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: E HDHRStreamHandler recorders/dtvsignalmonitor.cpp:323 (HandlePAT) DTVSigMon[5](1313B106-0): Program #0 not found in PAT!#012Program Association Section#012 PSIP tableID(0x0) length(13) extension(0x1)#012      version(0) current(1) section(0) last_section(0)#012      tsid(1) programCount(1)#012  program number  3349 has PID 0x0da2
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: E HDHRStreamHandler recorders/dtvsignalmonitor.cpp:329 (HandlePAT) DTVSigMon[5](1313B106-0): But there is only one program in the PAT, so we'll just use it
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler mpeg/mpegstreamdata.cpp:90 (SetDesiredProgram) MPEGStream[5](0x7f0624194450): SetDesiredProgram(3349)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler mpeg/mpegstreamdata.cpp:775 (ProcessPAT) MPEGStream[5](0x7f0624194450): ProcessPAT: PAT is missing program, setting timeout
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler mpeg/mpegstreamdata.cpp:791 (ProcessPAT) MPEGStream[5](0x7f0624194450): ProcessPAT: Good PAT seen after a bad PAT
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3914 (TuningSignalCheck) TVRec[5]: TuningSignalCheck: Good signal
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:730 (SetRecordingStatus) TVRec[5]: SetRecordingStatus(Tuning->Recording) on line 4012
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "Born to Explore With Richard Wiese":"Morocco: Secret Village" on cardid [5] (Tuning => Recording)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:2107 (TeardownSignalMonitor) TVRec[5]: TeardownSignalMonitor() -- begin
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/streamhandler.cpp:123 (RemoveListener) SH(1313B106-0): RemoveListener(0x7f0624194450) -- begin
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/streamhandler.cpp:135 (RemoveListener) SH(1313B106-0): RemoveListener(0x7f0624194450) -- locked
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:199 (run) HDHRSH(1313B106-0): RunTS(): shutdown
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: E HDHRStreamHandler recorders/hdhrstreamhandler.cpp:258 (UpdateFilters) HDHRSH(1313B106-0): UpdateFilters called in wrong tune mode
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:223 (run) HDHRSH(1313B106-0): stream stats: packet_count=39 network_errors=0 transport_errors=0 sequence_errors=0 overflow_errors=0
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:227 (run) HDHRSH(1313B106-0): RunTS(): end
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:231 (run) HDHRSH(1313B106-0): Release tuner lock.
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/streamhandler.cpp:152 (RemoveListener) SH(1313B106-0): RemoveListener(0x7f0624194450) -- end
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:2126 (TeardownSignalMonitor) TVRec[5]: TeardownSignalMonitor() -- end
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder, @ tv_rec.cpp:4038
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder, @ tv_rec.cpp:4040
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4165 (TuningNewRecorder) TVRec[5]: Starting Recorder
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4135 (LoadProfile) TVRec[5]: Using profile 'Default' to record
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4204 (TuningNewRecorder) TVRec[5]: rec->GetPathname(): '/home/llamont/mythtest/1472_20190703191100.ts'
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4236 (TuningNewRecorder) TVRec[5]: TuningNewRecorder - CreateRecorder()
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/dtvrecorder.cpp:182 (ResetForNewFile) DTVRec[5]: ResetForNewFile(void)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: E TVRecEvent recorders/recorderbase.cpp:232 (SetStrOption) RecBase[5](1313B106-0): SetStrOption(...recordingtype): Option not in profile.
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/recorderbase.cpp:101 (SetRingBuffer) RecBase[5](1313B106-0): SetRingBuffer(0x7f0624193550) '/home/llamont/mythtest/1472_20190703191100.ts'
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/recorderbase.cpp:111 (SetRecording) RecBase[5](1313B106-0): SetRecording(0x7f06080b35d0) title(Born to Explore With Richard Wiese)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent mpeg/mpegstreamdata.cpp:90 (SetDesiredProgram) MPEGStream[5](0x7f0624194450): SetDesiredProgram(3349)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/dtvrecorder.cpp:1289 (HandlePAT) DTVRec[5]: SetPAT(3349 on 0xda2)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/dtvrecorder.cpp:1309 (HandlePMT) DTVRec[5]: SetPMT(3349, valid)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/dtvrecorder.cpp:1314 (HandlePMT) DTVRec[5]: SetPMT(3349)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrrecorder.cpp:69 (run) HDHRRec[5]: run -- begin
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/dtvrecorder.cpp:182 (ResetForNewFile) DTVRec[5]: ResetForNewFile(void)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrstreamhandler.cpp:56 (Get) HDHRSH: Using existing stream handler 1313B106-0 for 1313B106-0 (2 in use)
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrrecorder.cpp:41 (Open) HDHRRec[5]: HDHR opened successfully
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4410 (SetFlags) TVRec[5]: SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4308
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(NeedToStartRecorder,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4310
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/streamhandler.cpp:81 (AddListener) SH[5](1313B106-0): AddListener(0x7f0624194450) -- begin
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/streamhandler.cpp:93 (AddListener) SH[5](1313B106-0): AddListener(0x7f0624194450) -- locked
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/streamhandler.cpp:115 (AddListener) SH[5](1313B106-0): AddListener(0x7f0624194450) -- end
Jul  3 15:11:22 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:145 (run) HDHRSH[5](1313B106-0): RunTS(): begin
Jul  3 15:11:23 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/dtvrecorder.cpp:623 (FindMPEG2Keyframes) DTVRec[5]: FindMPEG2Keyframes: frame rate = 29970
Jul  3 15:11:23 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/dtvrecorder.cpp:1599 (ProcessAVTSPacket) DTVRec[5]: PID 0xda3 Found Payload Start
Jul  3 15:11:23 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/dtvrecorder.cpp:1599 (ProcessAVTSPacket) DTVRec[5]: PID 0xda4 Found Payload Start
Jul  3 15:11:23 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/dtvrecorder.cpp:1599 (ProcessAVTSPacket) DTVRec[5]: PID 0xda5 Found Payload Start
Jul  3 15:11:23 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatagrabber.cpp:453 (RunGrabber) Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -N Born to Explore With Richard Wiese Morocco: Secret Village
Jul  3 15:11:24 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatagrabber.cpp:453 (RunGrabber) Running Grabber: /usr/share/mythtv/metadata/Television/ttvdb.py -l en -a US -M Born to Explore With Richard Wiese
Jul  3 15:11:26 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatagrabber.cpp:453 (RunGrabber) Running Grabber: /usr/share/mythtv/metadata/Movie/tmdb3.py -l en -a US -M Born to Explore With Richard Wiese
Jul  3 15:11:26 hms-server2 mythbackend: mythbackend[3300]: I MetadataDownload metadatadownload.cpp:187 (run) Metadata Lookup Failed: No Results Born to Explore With Richard Wiese 0 0
Jul  3 15:11:28 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:28 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(be4eb0) as a client (events: 0)
Jul  3 15:11:29 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(be4eb0) 'hms-server2' disconnected
Jul  3 15:11:33 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:33 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(be51c0) as a client (events: 0)
Jul  3 15:11:33 hms-server2 mythbackend: mythbackend[3300]: E ProcessRequest programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(2131_20190702190418.ts): GetPlaybackURL: '2131_20190702190418.ts' should be local, but it can not be found.
Jul  3 15:11:33 hms-server2 mythbackend: mythbackend[3300]: E ProcessRequest programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(2131_20190702190423.ts): GetPlaybackURL: '2131_20190702190423.ts' should be local, but it can not be found.
Jul  3 15:11:33 hms-server2 mythbackend: mythbackend[3300]: E ProcessRequest programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1510_20190702192623.ts): GetPlaybackURL: '1510_20190702192623.ts' should be local, but it can not be found.
Jul  3 15:11:33 hms-server2 mythbackend: mythbackend[3300]: E ProcessRequest programinfo.cpp:2608 (GetPlaybackURL) ProgramInfo(1510_20190702192628.ts): GetPlaybackURL: '1510_20190702192628.ts' should be local, but it can not be found.
Jul  3 15:11:34 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(be51c0) 'hms-server2' disconnected
Jul  3 15:11:34 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:34 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(cc70a0) as a client (events: 0)
Jul  3 15:11:34 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:34 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(c1dcb0) as a client (events: 1)
Jul  3 15:11:37 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(cc70a0) 'hms-server2' disconnected
Jul  3 15:11:37 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(109) mainserver.cpp:7674 (connectionClosed) Monitor sock(c1dcb0) 'hms-server2' disconnected
Jul  3 15:11:43 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:11:43 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(c10980) as a client (events: 0)
Jul  3 15:11:43 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(c10980) 'hms-server2' disconnected
Jul  3 15:12:15 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((1+1)%16!=3)  0.00%
Jul  3 15:12:15 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=9)  0.00%
Jul  3 15:12:15 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=3)  0.00%
Jul  3 15:12:15 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((3+1)%16!=2)  0.01%
Jul  3 15:12:15 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=14)  0.01%
Jul  3 15:12:15 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((2+1)%16!=4)  0.01%
Jul  3 15:12:43 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((6+1)%16!=14)  0.01%
Jul  3 15:12:43 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((9+1)%16!=7)  0.01%
Jul  3 15:12:43 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=10)  0.01%
Jul  3 15:16:02 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((12+1)%16!=3)  0.00%
Jul  3 15:16:02 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((9+1)%16!=11)  0.00%
Jul  3 15:16:02 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((11+1)%16!=10)  0.00%
Jul  3 15:16:02 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=13)  0.00%
Jul  3 15:16:02 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=9)  0.00%
Jul  3 15:16:02 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((10+1)%16!=12)  0.00%
Jul  3 15:16:35 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=8)  0.00%
Jul  3 15:16:35 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((3+1)%16!=6)  0.00%
Jul  3 15:16:35 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((12+1)%16!=3)  0.00%
Jul  3 15:16:35 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((6+1)%16!=4)  0.00%
Jul  3 15:16:35 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((7+1)%16!=13)  0.00%
Jul  3 15:16:35 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((5+1)%16!=7)  0.00%
Jul  3 15:16:52 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((0+1)%16!=2)  0.00%
Jul  3 15:16:52 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=4)  0.01%
Jul  3 15:16:52 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((9+1)%16!=14)  0.01%
Jul  3 15:16:52 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((2+1)%16!=1)  0.01%
Jul  3 15:16:52 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=10)  0.01%
Jul  3 15:16:52 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((1+1)%16!=3)  0.01%
Jul  3 15:17:20 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((11+1)%16!=2)  0.01%
Jul  3 15:17:20 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((11+1)%16!=13)  0.01%
Jul  3 15:17:20 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((7+1)%16!=12)  0.01%
Jul  3 15:17:20 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((13+1)%16!=12)  0.01%
Jul  3 15:17:20 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((1+1)%16!=8)  0.01%
Jul  3 15:17:20 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((12+1)%16!=14)  0.01%
Jul  3 15:18:39 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((5+1)%16!=11)  0.01%
Jul  3 15:18:39 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((7+1)%16!=9)  0.01%
Jul  3 15:18:39 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((0+1)%16!=6)  0.01%
Jul  3 15:18:39 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((9+1)%16!=8)  0.01%
Jul  3 15:18:39 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((10+1)%16!=1)  0.01%
Jul  3 15:18:39 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((8+1)%16!=10)  0.01%
Jul  3 15:18:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((11+1)%16!=3)  0.01%
Jul  3 15:18:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((15+1)%16!=12)  0.01%
Jul  3 15:18:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=0)  0.01%
Jul  3 15:19:08 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((15+1)%16!=6)  0.01%
Jul  3 15:19:08 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((1+1)%16!=0)  0.01%
Jul  3 15:19:08 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((5+1)%16!=2)  0.01%
Jul  3 15:19:24 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((11+1)%16!=2)  0.01%
Jul  3 15:19:24 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((4+1)%16!=6)  0.01%
Jul  3 15:19:24 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((14+1)%16!=12)  0.01%
Jul  3 15:19:24 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((6+1)%16!=5)  0.01%
Jul  3 15:19:24 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((1+1)%16!=15)  0.01%
Jul  3 15:19:24 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((5+1)%16!=7)  0.01%
Jul  3 15:19:50 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=4)  0.01%
Jul  3 15:19:50 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((9+1)%16!=14)  0.01%
Jul  3 15:19:50 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=10)  0.01%
Jul  3 15:19:53 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=9)  0.01%
Jul  3 15:19:53 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((14+1)%16!=3)  0.01%
Jul  3 15:19:53 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=15)  0.01%
Jul  3 15:19:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((7+1)%16!=14)  0.01%
Jul  3 15:19:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((2+1)%16!=4)  0.01%
Jul  3 15:19:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=8)  0.01%
Jul  3 15:19:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((4+1)%16!=3)  0.01%
Jul  3 15:19:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=4)  0.01%
Jul  3 15:19:55 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((3+1)%16!=5)  0.01%
Jul  3 15:20:03 hms-server2 mythbackend: mythbackend[3300]: I HttpServer73 programinfo.cpp:6185 (GetFilesize) ProgramInfo(1472_20190703191100.ts): RI Filesize=0, DB Filesize=135117480
Jul  3 15:20:03 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1738 (HandleAnnounce) MainServer: MainServer::ANN Monitor
Jul  3 15:20:03 hms-server2 mythbackend: mythbackend[3300]: I ProcessRequest mainserver.cpp:1743 (HandleAnnounce) MainServer: adding: hms-server2(be51c0) as a client (events: 0)
Jul  3 15:20:04 hms-server2 mythbackend: mythbackend[3300]: I MythSocketThread(73) mainserver.cpp:7674 (connectionClosed) Monitor sock(be51c0) 'hms-server2' disconnected
Jul  3 15:20:17 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((1+1)%16!=8)  0.01%
Jul  3 15:20:17 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=2)  0.01%
Jul  3 15:20:17 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((7+1)%16!=4)  0.01%
Jul  3 15:20:52 hms-server2 mythbackend: mythbackend[3300]: N Expire autoexpire.cpp:251 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
Jul  3 15:21:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((6+1)%16!=13)  0.01%
Jul  3 15:21:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((12+1)%16!=8)  0.01%
Jul  3 15:21:47 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((12+1)%16!=3)  0.01%
Jul  3 15:21:47 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((13+1)%16!=15)  0.01%
Jul  3 15:21:47 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=13)  0.01%
Jul  3 15:21:47 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((15+1)%16!=14)  0.01%
Jul  3 15:21:47 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=9)  0.01%
Jul  3 15:21:47 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((14+1)%16!=0)  0.01%
Jul  3 15:22:32 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((9+1)%16!=0)  0.01%
Jul  3 15:22:32 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((13+1)%16!=15)  0.01%
Jul  3 15:22:32 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((5+1)%16!=10)  0.01%
Jul  3 15:22:32 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((15+1)%16!=14)  0.01%
Jul  3 15:22:32 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((15+1)%16!=6)  0.01%
Jul  3 15:22:32 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((14+1)%16!=0)  0.01%
Jul  3 15:22:56 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((15+1)%16!=6)  0.01%
Jul  3 15:22:56 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((11+1)%16!=0)  0.01%
Jul  3 15:22:56 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((5+1)%16!=12)  0.01%
Jul  3 15:23:00 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((7+1)%16!=14)  0.01%
Jul  3 15:23:00 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((0+1)%16!=2)  0.01%
Jul  3 15:23:00 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=8)  0.01%
Jul  3 15:23:00 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((2+1)%16!=1)  0.01%
Jul  3 15:23:00 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((1+1)%16!=3)  0.01%
Jul  3 15:23:00 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=4)  0.01%
Jul  3 15:23:06 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((2+1)%16!=9)  0.01%
Jul  3 15:23:06 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=3)  0.01%
Jul  3 15:23:06 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=14)  0.01%
Jul  3 15:23:33 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((14+1)%16!=5)  0.01%
Jul  3 15:23:33 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((8+1)%16!=10)  0.01%
Jul  3 15:23:33 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((10+1)%16!=15)  0.01%
Jul  3 15:23:33 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((10+1)%16!=9)  0.01%
Jul  3 15:23:33 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((4+1)%16!=11)  0.01%
Jul  3 15:23:33 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((9+1)%16!=11)  0.01%
Jul  3 15:26:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((4+1)%16!=11)  0.01%
Jul  3 15:26:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((3+1)%16!=5)  0.01%
Jul  3 15:26:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((15+1)%16!=5)  0.01%
Jul  3 15:26:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((5+1)%16!=4)  0.01%
Jul  3 15:26:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((10+1)%16!=0)  0.01%
Jul  3 15:26:07 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda5 discontinuity detected ((4+1)%16!=6)  0.01%
Jul  3 15:26:54 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((4+1)%16!=12)  0.01%
Jul  3 15:26:54 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((1+1)%16!=5)  0.01%
Jul  3 15:26:54 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((11+1)%16!=2)  0.01%
Jul  3 15:27:14 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=9)  0.01%
Jul  3 15:27:14 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((11+1)%16!=14)  0.01%
Jul  3 15:27:14 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((13+1)%16!=4)  0.01%
Jul  3 15:27:14 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((14+1)%16!=12)  0.01%
Jul  3 15:27:14 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=14)  0.01%
Jul  3 15:27:14 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((13+1)%16!=15)  0.01%
Jul  3 15:28:58 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((4+1)%16!=11)  0.01%
Jul  3 15:28:58 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((0+1)%16!=5)  0.01%
Jul  3 15:28:58 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((10+1)%16!=1)  0.01%
Jul  3 15:28:59 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((3+1)%16!=9)  0.01%
Jul  3 15:28:59 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((14+1)%16!=1)  0.01%
Jul  3 15:28:59 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((14+1)%16!=4)  0.01%
Jul  3 15:28:59 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((1+1)%16!=15)  0.01%
Jul  3 15:28:59 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda3 discontinuity detected ((8+1)%16!=15)  0.01%
Jul  3 15:28:59 hms-server2 mythbackend: mythbackend[3300]: W HDHRStreamHandler recorders/dtvrecorder.cpp:1592 (ProcessAVTSPacket) DTVRec[5]: A/V PID 0xda4 discontinuity detected ((0+1)%16!=2)  0.01%
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(FinishRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:1352
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:1090 (HandleStateChange) TVRec[5]: Changing from RecordingOnly to None
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:1322
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3506 (HandleTuning) TVRec[5]: HandleTuning Request: Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,)
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3575 (TuningShutdowns) TVRec[5]: TuningShutdowns(Program(NULL) channel() input() flags(CloseRec,KillRingBuffer,))
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:1139 (TeardownRecorder) TVRec[5]: TeardownRecorder()
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrrecorder.cpp:124 (run) HDHRRec[5]: run -- ending...
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/streamhandler.cpp:123 (RemoveListener) SH[5](1313B106-0): RemoveListener(0x7f0624194450) -- begin
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/streamhandler.cpp:135 (RemoveListener) SH[5](1313B106-0): RemoveListener(0x7f0624194450) -- locked
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:199 (run) HDHRSH[5](1313B106-0): RunTS(): shutdown
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: E HDHRStreamHandler recorders/hdhrstreamhandler.cpp:258 (UpdateFilters) HDHRSH[5](1313B106-0): UpdateFilters called in wrong tune mode
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:223 (run) HDHRSH[5](1313B106-0): stream stats: packet_count=223892 network_errors=78 transport_errors=0 sequence_errors=0 overflow_errors=0
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:227 (run) HDHRSH[5](1313B106-0): RunTS(): end
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I HDHRStreamHandler recorders/hdhrstreamhandler.cpp:231 (run) HDHRSH[5](1313B106-0): Release tuner lock.
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/streamhandler.cpp:152 (RemoveListener) SH[5](1313B106-0): RemoveListener(0x7f0624194450) -- end
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrrecorder.cpp:48 (Close) HDHRRec[5]: Close() -- begin
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrrecorder.cpp:54 (Close) HDHRRec[5]: Close() -- end
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: N RecThread recorders/recorderbase.cpp:501 (FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec: mpeg2video (720x480 A/R: 3 29.97fps) Audio Codec: ac3
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I RecThread recorders/hdhrrecorder.cpp:138 (run) HDHRRec[5]: run -- end
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(NeedToStartRecorder,RecorderRunning,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:1152
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recordingquality.cpp:86 (RecordingQuality) RecordingQuality() start(2019-07-03T19:11:00Z) end(2019-07-03T19:30:00Z) score(0.942105)
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/recorderbase.cpp:113 (SetRecording) RecBase[5](1313B106-0): SetRecording(0x0)
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:863 (FinishedRecording) TVRec[5]: FinishedRecording(1472_2019-07-03T19:11:00Z) damaged recq:<RecordingQuality overall_score="0.942105" key="1472_2019-07-03T19:11:00Z" countinuity_error_count="122" packet_count="1543600">#012    <Gap start="2019-07-03T19:11:00Z" end="2019-07-03T19:11:22Z" duration="22" />#012</RecordingQuality>
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:914 (FinishedRecording) TVRec[5]: FinishedRecording(1472_2019-07-03T19:11:00Z) Bad quality#012#011#011#011title: Born to Explore With Richard Wiese#012#011#011#011in recgroup: Default status: Recording:Recorded not_dummy finished_now
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent programinfo.cpp:4722 (SaveVideoProperties) SaveVideoProperties(0x3a, 0x22)
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent programinfo.cpp:6185 (GetFilesize) ProgramInfo(1472_20190703191100.ts): RI Filesize=0, DB Filesize=294391832
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:971 (FinishedRecording) TVRec[5]: FinishedRecording -- UPDATE_RECORDING_STATUS: Recorder Failed
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent recorders/hdhrstreamhandler.cpp:513 (TuneChannel) HDHRSH(1313B106-0): Tuning channel none (was qam:513000000)
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:3637 (TuningShutdowns) TVRec[5]: Tearing down RingBuffer
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I TVRecEvent tv_rec.cpp:4419 (ClearFlags) TVRec[5]: ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, @ tv_rec.cpp:3643
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: E CoreContext mainserver.cpp:1172 (customEvent) MainServer: PREVIEW_SUCCESS but no receivers.
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I CoreContext scheduler.cpp:734 (UpdateRecStatus) Updating status for "Born to Explore With Richard Wiese":"Morocco: Secret Village" on cardid [5] (Recording => Recorder Failed)
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I Scheduler scheduler.cpp:2356 (HandleReschedule) Reschedule requested for CHECK -9 318 0 UpdateRecStatus2 | Born to Explore With Richard Wiese | Morocco: Secret Village | A village in the remote Atlas Mountains that has never been visited by Westerners; Berbers teach the secrets for traditional tanjia cooking and making butter in a goat bladder; traditional wedding ceremony. | EP014417700149
Jul  3 15:30:00 hms-server2 mythbackend: mythbackend[3300]: I Scheduler scheduler.cpp:2469 (HandleReschedule) Scheduled 96 items in 0.2 = 0.10 match + 0.00 check + 0.09 place
Attachments
NAS_SMART_values.txt
(6.98 KiB) Downloaded 122 times
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

I may have found something. I decided to test the hdhomerun status and a 4-5 minute test using hdhomerun_config yielded this:

Code: Select all

-- Video statistics --
670106 packets received, 0 overflow errors, 3878 network errors, 0 transport errors, 0 sequence errors
I'll continue eliminating possibilities.
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

After testing network throughput from the Linux container (internet up/download speeds - 100Mbps up and down), I tested read and write speeds to/from my Windows desktop that is wired into the same GB switch the hdhomeruns are, then to/from a NAS directory. The results were as follows:

Copy to Windows Share(write)

$sudo dd if=tempfile of=./share/tempfile bs=1M count=1024
1024+0 records in
1024+0 records out
1073741824 bytes (1.1 GB, 1.0 GiB) copied, 16.4274 s, 65.4 MB/s

Copy From Windows share(read)
$sudo dd if=./share/tempfile of=./sharInFile bs=1M count=1024
1024+0 records in
1024+0 records out
1073741824 bytes (1.1 GB, 1.0 GiB) copied, 10.4584 s, 103 MB/s

Copy to NAS Share
dd if=tempfile of=/share2/Multimedia/speedTest/tempfile bs=1M count=1024
1024+0 records in
1024+0 records out
1073741824 bytes (1.1 GB, 1.0 GiB) copied, 31.4303 s, 34.2 MB/s

Copy from NAS Share
dd if=/share2/Multimedia/speedTest/tempfile of=./temp2file bs=1M count=1024
1024+0 records in
1024+0 records out
1073741824 bytes (1.1 GB, 1.0 GiB) copied, 6.47898 s, 166 MB/s

From this I see write speeds of only 34MB/s, that's about 270 mb/s. I would think that's enough.
I studied the damaged recordings last night and though they are view-able I did see a fair amount of tearing through out the recordings. Though not really annoying, it would likely account for a damaged file indication. The commercial skip actually worked on a couple of the damaged files.

I re-ran hdhomerun tests and observed something odd. while saving a stream I got this:
./testHomerun.sh
ch=qam:279000000 lock=qam256 ss=100 snq=98 seq=100 bps=0 pps=0
nnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnn.nnnnnnnnnnnnnnnnnnnnnnnnnnnnnnn.nnnnnnnnnnnnnnnnnnnnnnn.nnnnnnnnn.nnnnnnnnnnnnnnnnnnnnnnnnnnnnn.nnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnnn^C
-- Video statistics --
826979 packets received, 0 overflow errors, 2348 network errors, 0 transport errors, 0 sequence errors.

However, when I pull debug info from another task, the results seem normal:
hdhomerun_config 1313b106 get /tuner2/debug
tun: ch=qam:279000000 lock=qam256:279000000 ss=100 snq=100 seq=100 dbg=-344/-153
dev: bps=38810720 resync=0 overflow=0
cc: bps=38810720 resync=0 overflow=0
ts: bps=38809216 te=0 crc=0
net: pps=3686 err=0 stop=0.

Once again, any help or suggestions would be greatly appreciated.

Larry
joelmr
Newcomer
Posts: 2
Joined: Mon May 03, 2021 6:34 pm
United States of America

Re: All Recordings marked Damaged

Post by joelmr »

Did you ever figure this out? Something has changed on my system recently (too many things, all at once, actually, and I can't go back now), and I'm having the same problems:
  • Testing the HDHomeRun shows frequent UDP errors
  • All recordings are marked as damaged
  • Disk and network otherwise work just fine, and should be plenty fast
I have observed that when using the HDHomeRun viewing app itself, either on my Mac, or my phone, the image quality is pixel-perfect, and I can also see, using hdhomerun_config_gui, that the connection is being made over http.

I don't think I'll have much luck tracking down the UDP problem, and I also don't know if it was this way before and just hadn't been a problem. So, I'm now instead looking at using the "external recorder" option, which is apparently the only way to get mythtv to use http instead of UDP. I'm having trouble getting it working, though, did you head down this path too? I'll avoid hijacking this thread, though, and post separately about that. Thanks.

-Joel
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

Wow, sorry to hear that. I fussed with my set-up for months. As you can see above, I looked under a lot of rocks to find what was failing. For what it's worth, I made a huge mistake back in the beginning when I upgraded my NAS, mythTV and my Ubuntu VM all in the same day. NOT SMART!
Anyway, it simply appeared I was suffering from frequent, random network congestion. Of course I wasn't but that is what it looked like. So, when the precise diagnostic approach fails.....pull out the shotgun and do some serious engineering :-).
I bought a raspberry Pi 4 and moved the myth backend to it and left my database on the NAS. Well, that brought up some new problems. I really don't know, but I think MariaDB was not performing writes well when running in a docker process on the QNAP NAS. Once I moved MariaDB to the Raspberry Pi all the problems cleared up. I did however have to leave the commercial flagging task in a docker task on the NAS. The Pi just isn't up to that level of activity.
Let me know if you need more info.
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

Oh, additionally, I left all the recordings on the NAS and it's working fine.
joelmr
Newcomer
Posts: 2
Joined: Mon May 03, 2021 6:34 pm
United States of America

Re: All Recordings marked Damaged

Post by joelmr »

I ended up slogging through the external recorder method and got it working (after several days of wiki and blog reading, and trial and error). There probably is something, somewhere, that's fishy with my network that's causing UDP traffic to be unreliable, but since I got my main problem sorted, the priority of the networking stuff has dropped.
pblarry49
Newcomer
Posts: 9
Joined: Tue Jul 02, 2019 5:14 pm
United States of America

Re: All Recordings marked Damaged

Post by pblarry49 »

Good, I'm glad you got it working. We use our mythTV and AppleTV every day. I know there are a lot of cloud based DVRs and there is always the 'on demand' streaming stuff but I'm an old fogey and like my ones and zeros right where I can count 'em.
Post Reply