Mailing List Archive

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Have not yet tried the above commit. Will do so shortly. However by
increasing the number of udp buffers in the system I can now record 3 HD
streams with no packet loss. This is a big improvement will test further
[https://medium.com/@CameronSparr/increase-os-udp-buffers-to-improve-
performance-51d167bb1360] .Used


{{{
If the values are less than 26214400 bytes (25MB) you should add the
following lines to the /etc/sysctl.conf file:

net.core.rmem_max=26214400
net.core.rmem_default=26214400
}}}

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:43>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Apologies I had not noticed that you had already posted on the benefits of
increasing the UDP buffering. Having increased the buffering and taken
f0c708a89a3b762fa3ccbe3d8ccede7745000649 the system is performing well.
There was a bad patch yesterday when :-

continuity_error_count="4409" packet_count="21125940"

This bad patch occurred in the last 5 minutes of the recording on BBC One
HD - in the overrun time (in the 5 mins after the recording was scheduled
to finish). It occurs to me that this was because 'schedule as group' is
the default settings in mythtv-setup. So a new recording had been started
in parallel on that tuner while the old one was completing its overun
time.

I have now removed schedule as group from all the capture card inputs
which I think will improve stability.

According to the 'monitor' page of the Digibit, normal HD channels show
average rates up to 10000Kbits average. However I am now running

SatIPSH[6](uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1): Tune
url:rtsp://192.168.0.44:554/?fe=2&freq=10847.00&pol=v&ro=0.20&msys=dvbs2&mtype=8psk&sr=23000&fec=34&plts=auto

is showing 50000Kbits average. The BBC One / Two HD mux seems to go
straight to 50k independently of how many channels are being viewed.

I have now ditched my USB tuner and am using 3 inputs from the Digibox and
it is working great including EPG many thanks.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:44>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

Thanks for reporting back.

About the UDP buffer size. I've looked in the HDHomeRun library and there
the UDP buffer size is set to 1024x1024 bytes, the actual value depending
on the net.core.rmem_max value of the system. Note that it overall saves
memory if you only set the net.core.rmem_max value and not the
net.core.rmem_default because MythTV only extends the buffer size for the
socket that is actually used for the video data stream. There is also a
socket for the control data and the buffer size of that socket does not
need to be changed.

I've looked into the "bad patch" problem and this is caused by the
multirec feature not yet being implemented completely. What happens is
that when your second recording starts the box is tuned again, on the same
channel. This causes the disruption in your first recording. The correct
behavior is to recognize that the channel is already in use and that it is
already correctly tuned so the tuning can be skipped.
This is how it is implemented for the /dev/dvb/adapter*/* devices
(PCI/PCIe, USB) and this is also how it should be done for SatIP.

About the bitrate of the incoming stream. The code is now such that when
more than 32 PIDs are requested the full transport stream is received.
When there are less PIDs requested then the Sat>IP box is doing the PID
filtering so then you only receive the PIDs that are needed.
This is done because the Digibit R1 box effectively dies when your request
more than 36 or 37 PIDs. The minisatip software, running on a PC, does not
have this limitation but we write software for the worst case situation.
Still to investigate why recording a channel on the BBC One/Two HD mux
requires so many PIDS that it needs to receive the full transport stream.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:45>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------
Changes (by jksj461):

* Attachment "DroppedPacketLog02-12-20.zip" added.


--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Had 5 problem free days running 3 tuners and schedule as group disabled.
Allowing two recordings on the first tuner to benefit from BBc One & Two
Hd being on the same mux. This should not work for the reasons you
describe.

{{{
+--------+-------------+-------------+-----------+------------+-------------+-------------+
| cardid | displayname | recpriority | quicktune | schedorder |
livetvorder | dvb_eitscan |
+--------+-------------+-------------+-----------+------------+-------------+-------------+
| 1 | Frs1 | 4 | 0 | 1 |
1 | 1 |
| 2 | Frs2 | 3 | 0 | 1 |
1 | 0 |
| 3 | Frs3 | 2 | 0 | 1 |
1 | 0 |
| 4 | Frs1 | 4 | 0 | 1 |
1 | 1 |
+--------+-------------+-------------+-----------+------------+-------------+-------------+

}}}

So connected all 4 inputs to the LNB and ran the system with only one
recording allowed per card.
This fell over at the transition time 9 pm again with lots of drop
packets. A log is attached - it may be noteworthy that there is no tuning
attempt for the program Harlots at 9 pm it must be trying to attach to an
existing stream. Restarted the backend after 4 mins and it did the same
thing again straight away ... mystified.
Went back to my original configuration 3 inputs with two recordings
allowed on the first. Seems bomb proof have been soak testing it ever
since wth every sort of transition I can find between one or two
recordings on the first tuner.

Log file added to attachments - apologies seems to have been attached to
previous post? Just incompetance.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:46>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

Again thanks for testing!

The latest update, commit f5e0fc0cf812b7615d571ef217e50229301bd5ef, skips
the tuning part if you have a second recording on the same multiplex and
thus avoids the "Sequence error" and the associated packet loss.

Next step is that the SatIP implementation will be restructured along the
lines of how the HDHomeRun support is implemented. This is a comparable
device, with four independent tuners and use of UDP packets for the video
streaming. The HDHomeRun works OK with only a 1Mbyte UDP input buffer
(instead of 8Mbyte) and it does not do extra buffering at the input. The
current SatIP implementation is largely inspired by how it is done for
Ceton and IPTV.


This should fix the remaining issues:
- inefficient/slow due to buffering all received input data
- allow to close the input device when not in use
- allow restart of the backend

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:47>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

The current implementation is performing very well many thanks.
I will test anything you commit.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:48>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by gigem):

Replying to [comment:47 Klaas de Waal]:
> Next step is that the SatIP implementation will be restructured along
the lines of how the HDHomeRun support is implemented. This is a
comparable device, with four independent tuners and use of UDP packets for
the video streaming. The HDHomeRun works OK with only a 1Mbyte UDP input
buffer (instead of 8Mbyte) and it does not do extra buffering at the
input. The current SatIP implementation is largely inspired by how it is
done for Ceton and IPTV.

Klaas, FYI, I had a serious problem this past summer with frequent
corruption in most recordings. With help, I traced the problem to lack
of buffer space for my HDHR and Ceton tuners. I suspected a Linux kernel
change was to blame as I'd never had the problem before and I don't
believe the MythTV recorders had changed. Regardless of the cause, I
raised my HDRingbufferSize setting to 75200 to get rid of the corruption.
I have plenty of memory in my backend so I didn't spend any time backing
that value down to see how low it could go without causing the problem.

Do the SatIP devices support TCP? From what I have gathered from other,
HDHR users, using TCP does not have the same buffering problem and is also
the recommended, access method from SiliconDust. If the SaiIP boxes
support TCP, you should consider using it, at least optionally.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:49>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

mythbackend version: (HEAD detached at 7de9c58ad4)
[v32-Pre-1753-g7de9c58ad4] www.mythtv.org

Had faultless recordings for the last 4 weeks until last night. I think
that attempting to record more than one program on the same mux causes
tuning problems where only a single channel is allowed.
The above condition is unlikely to occur within my configuration so the
above failure may be the only occurrence within the period.
The issue arises probably due to me over constraining the configuration to
get deterministic behaviour. I allow two simultaneous recording off the
first mux and constrain the rest to one. This normally works fine as
BBC1/2 HD are the lowest channel numbers and are normally allocated to
this first mux. In the case in question the first mux was already
allocated.
Do you recommend me setting Schedule as Group for all tuners or setting
max recordings to 2 for all tuners (which do you use).

{{{
Dec 31 22:24:30 I TVRecEvent tv_rec.cpp:1620 (HandlePendingRecordings)
TVRec[2]: ASK_RECORDING 2 29 0 0
Dec 31 22:25:00 I TVRecEvent tv_rec.cpp:1061 (HandleStateChange)
TVRec[2]: Changing from None to RecordingOnly
Dec 31 22:25:00 I TVRecEvent mythdbcon.cpp:419 (PurgeIdleConnections) New
DB connection, total: 11
Dec 31 22:25:00 I TVRecEvent tv_rec.cpp:3661 (TuningFrequency) TVRec[2]:
TuningFrequency
Dec 31 22:25:00 I TVRecEvent recorders/satipstreamhandler.cpp:216 (Tune)
SatIPSH[2]: Tune 10847000
Dec 31 22:25:00 I Scheduler scheduler.cpp:2889
(HandleRecordingStatusChange) Tuning recording: "The Graham Norton New
Year's Eve Show": channel 65792 on cardid [2], sourceid 1
Dec 31 22:25:00 C CoreContext programinfo.cpp:252 (ProgramInfo)
ProgramInfo(): Failed to find recorded entry for 0.
Dec 31 22:25:01 I CoreContext scheduler.cpp:713 (UpdateRecStatus)
Updating status for "The Graham Norton New Year's Eve Show" on cardid [2]
(Tuning => Recording)
Dec 31 22:25:01 I TVRecEvent tv_rec.cpp:4208 (TuningNewRecorder)
TVRec[2]: rec->GetPathname(): '/media/library/TV/65792_20201231222500.ts'
Dec 31 22:25:01 I TVRecEvent tv_rec.cpp:4241 (TuningNewRecorder)
TVRec[2]: TuningNewRecorder - CreateRecorder()
Dec 31 22:25:01 E TVRecEvent recorders/recorderbase.cpp:216
(SetStrOption) RecBase[2](uuid:3eec0475-c365-44e7-bf7c-
6d53b0daf8e4:DVBS2:2): SetStrOption(...recordingtype): Option not in
profile.

Dec 31 22:44:31 I TVRecEvent tv_rec.cpp:1620 (HandlePendingRecordings)
TVRec[3]: ASK_RECORDING 3 29 0 0
Dec 31 22:45:00 I TVRecEvent tv_rec.cpp:1061 (HandleStateChange)
TVRec[3]: Changing from None to RecordingOnly
Dec 31 22:45:00 I TVRecEvent mythdbcon.cpp:419 (PurgeIdleConnections) New
DB connection, total: 14
Dec 31 22:45:00 I TVRecEvent tv_rec.cpp:3661 (TuningFrequency) TVRec[3]:
TuningFrequency
Dec 31 22:45:00 I TVRecEvent recorders/satipstreamhandler.cpp:216 (Tune)
SatIPSH[3]: Tune 10847000
Dec 31 22:45:00 I Scheduler scheduler.cpp:2889
(HandleRecordingStatusChange) Tuning recording: "Mock the Week": channel
65791 on cardid [3], sourceid 1
Dec 31 22:45:00 N Scheduler scheduler.cpp:3246 (HandleIdleShutdown)
Blocking shutdown because of an active encoder
Dec 31 22:45:00 N Scheduler scheduler.cpp:3257 (HandleIdleShutdown)
Blocking shutdown because of delay request from external application
Dec 31 22:45:00 C CoreContext programinfo.cpp:252 (ProgramInfo)
ProgramInfo(): Failed to find recorded entry for 0.
Dec 31 22:45:01 I CoreContext scheduler.cpp:713 (UpdateRecStatus)
Updating status for "Mock the Week" on cardid [3] (Tuning => Recording)
Dec 31 22:45:01 I TVRecEvent tv_rec.cpp:4208 (TuningNewRecorder)
TVRec[3]: rec->GetPathname(): '/media/library/TV/65791_20201231224500.ts'
Dec 31 22:45:01 I TVRecEvent tv_rec.cpp:4241 (TuningNewRecorder)
TVRec[3]: TuningNewRecorder - CreateRecorder()
Dec 31 22:45:01 E TVRecEvent recorders/recorderbase.cpp:216
(SetStrOption) RecBase[3](uuid:3eec0475-c365-44e7-bf7c-
6d53b0daf8e4:DVBS2:3): SetStrOption(...recordingtype): Option not in
profile.

Dec 31 23:20:00 I TVRecEvent tv_rec.cpp:1061 (HandleStateChange)
TVRec[3]: Changing from RecordingOnly to None
Dec 31 23:20:01 N RecThread recorders/recorderbase.cpp:491
(FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec:
h264 (1920x1088 A/R: 3 25fps) Audio Codec: ac3
Dec 31 23:20:01 I TVRecEvent tv_rec.cpp:825 (FinishedRecording) TVRec[3]:
FinishedRecording(65791_2020-12-31T22:45:00Z) damaged
recq:<RecordingQuality overall_score="0.8"
key="65791_2020-12-31T22:45:00Z" continuity_error_count="25014"
packet_count="10025590" />
Dec 31 23:20:02 I CoreContext scheduler.cpp:713 (UpdateRecStatus)
Updating status for "Mock the Week" on cardid [3] (Recording => Recorder
Failed)
Dec 31 23:20:02 I Scheduler scheduler.cpp:2321 (HandleReschedule)
Reschedule requested for CHECK -9 7289 0 UpdateRecStatus2 | Mock the Week
| | Dara O Briain and Hugh Dennis are joined by an array of guests in a
special edition of the show. Contains some strong language. |
fp.bbc.co.uk/m/ek0u
Dec 31 23:20:02 I Scheduler scheduler.cpp:2438 (HandleReschedule)
Scheduled 79 items in 0.1 = 0.00 match + 0.01 check + 0.11 place
Dec 31 23:20:02 I ProcessRequest mainserver.cpp:1801 (HandleAnnounce)
MainServer: MainServer::ANN Playback
Dec 31 23:20:02 I ProcessRequest mainserver.cpp:1803 (HandleAnnounce)
MainServer: adding: tv(55ed2f0fec60) as a client (events: 0)
Dec 31 23:20:03 I ProcessRequest mainserver.cpp:1801 (HandleAnnounce)
MainServer: MainServer::ANN Monitor
Dec 31 23:20:03 I ProcessRequest mainserver.cpp:1803 (HandleAnnounce)
MainServer: adding: tv(55ed2ec63030) as a client (events: 1)
Dec 31 23:20:16 I MythSocketThread(75) mainserver.cpp:7845
(connectionClosed) Playback sock(55ed2f0fec60) 'tv' disconnected
Dec 31 23:20:16 I MythSocketThread(88) mainserver.cpp:7845
(connectionClosed) Monitor sock(55ed2ec63030) 'tv' disconnected
Dec 31 23:21:02 N Expire autoexpire.cpp:241 (CalcParams) AutoExpire:
CalcParams(): Max required Free Space: 12.0 GB w/freq: 7 min
Dec 31 23:23:20 N HttpServer70 services/myth.cpp:950 (DelayShutdown)
Shutdown delayed 5 minutes for external application.
Dec 31 23:27:20 N HttpServer70 services/myth.cpp:950 (DelayShutdown)
Shutdown delayed 5 minutes for external application.
Dec 31 23:28:02 N Expire autoexpire.cpp:241 (CalcParams) AutoExpire:
CalcParams(): Max required Free Space: 12.0 GB w/freq: 7 min
Dec 31 23:30:00 N Scheduler scheduler.cpp:3246 (HandleIdleShutdown)
Blocking shutdown because of an active encoder
Dec 31 23:30:00 N Scheduler scheduler.cpp:3253 (HandleIdleShutdown)
Blocking shutdown because of active jobs
Dec 31 23:30:00 N Scheduler scheduler.cpp:3257 (HandleIdleShutdown)
Blocking shutdown because of delay request from external application
Dec 31 23:30:08 I Metadata_20880 jobqueue.cpp:2196
(DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "The
Graham Norton New Year's Eve Show" recorded from channel 65792 at
2020-12-31T22:25:00Z
Dec 31 23:30:11 I ProcessRequest mainserver.cpp:1801 (HandleAnnounce)
MainServer: MainServer::ANN Playback
Dec 31 23:30:11 I ProcessRequest mainserver.cpp:1803 (HandleAnnounce)
MainServer: adding: tv(55ed2ec65a50) as a client (events: 0)
Dec 31 23:30:11 I ProcessRequest mainserver.cpp:1801 (HandleAnnounce)
MainServer: MainServer::ANN Monitor
Dec 31 23:30:11 I ProcessRequest mainserver.cpp:1803 (HandleAnnounce)
MainServer: adding: tv(55ed2fc75f70) as a client (events: 1)
Dec 31 23:30:15 I MythSocketThread(70) mainserver.cpp:7845
(connectionClosed) Playback sock(55ed2ec65a50) 'tv' disconnected
Dec 31 23:30:15 I MythSocketThread(86) mainserver.cpp:7845
(connectionClosed) Monitor sock(55ed2fc75f70) 'tv' disconnected
Dec 31 23:30:35 I Scheduler scheduler.cpp:2321 (HandleReschedule)
Reschedule requested for MATCH 0 1 0 2021-01-01T00:00:00Z EITScanner
Dec 31 23:30:35 I Scheduler scheduler.cpp:2438 (HandleReschedule)
Scheduled 79 items in 0.2 = 0.07 match + 0.04 check + 0.11 place
Dec 31 23:31:20 N HttpServer59 services/myth.cpp:950 (DelayShutdown)
Shutdown delayed 5 minutes for external application.
Dec 31 23:35:00 I TVRecEvent tv_rec.cpp:1061 (HandleStateChange)
TVRec[2]: Changing from RecordingOnly to None
Dec 31 23:35:00 I RecThread mythdbcon.cpp:419 (PurgeIdleConnections) New
DB connection, total: 13
Dec 31 23:35:00 N RecThread recorders/recorderbase.cpp:491
(FinishRecording) Finished Recording: Container: MPEG2-TS Video Codec:
h264 (1920x1088 A/R: 3 25fps) Audio Codec: ac3
Dec 31 23:35:00 I TVRecEvent tv_rec.cpp:825 (FinishedRecording) TVRec[2]:
FinishedRecording(65792_2020-12-31T22:25:00Z) damaged
recq:<RecordingQuality overall_score="0.8"
key="65792_2020-12-31T22:25:00Z" continuity_error_count="26445"
packet_count="22679561" />
Dec 31 23:35:00 I CoreContext scheduler.cpp:713 (UpdateRecStatus)
Updating status for "The Graham Norton New Year's Eve Show" on cardid [2]
(Recording => Recorder Failed)
Dec 31 23:35:00 I Scheduler scheduler.cpp:2321 (HandleReschedule)
Reschedule requested for CHECK -9 6760 0 UpdateRecStatus2 | The Graham
Norton New Year's Eve Show | | With Tom Hanks, Emily Blunt, Jamie Dornan,
Hugh Fearnley-Whittingstall, Nish Kumar and Jessica Chastain. Sophie
Ellis-Bextor performs Crying at the Discotheque. | fp.bbc.co.uk/m/eixn
Dec 31 23:35:00 I Scheduler scheduler.cpp:2438 (HandleReschedule)
Scheduled 78 items in 0.1 = 0.00 match + 0.00 check + 0.10 place

}}}

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:50>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

It could be that your SatIP box has become unhappy. I have that
incidentally when testing and then I reboot the box with the web
interface.

I generally have the "Schedule as Group" enabled and then virtual tuners
are created when needed. This does not change the load on the SatIP box
that much, only a few additional PIDs are selected. There is a switchover
point when more than 32 PIDs are selected; in that case all PIDs are
received and all PID filtering is done by software somewhere. Note that
this can already happen with a single channel, e.g. channel 101 "BBC One
London" wants 36 PIDs.

When I test this with the Raspberry Pi 3B then it can get overloaded
especially when it is doing EIT on the second input, which can also need
the full transport stream.
On a computer with a real 1Gb/s network interface this should not be a
limitation.

In your configuration, with (IIRC) a number of inputs configured all to
receive the same satellite, I would connect all inputs to the same video
source, select "Schedule as Group" on all inputs and let the system figure
it all out.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:51>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Thanks changed to use "Schedule as Group". Can't currently break it so
great!. Currently trying to learn enough python to reset the Digibit when
the backend is shutdown.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:52>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Just updated current master from v32-Pre-2005-g4ce4d36b2b to
v32-Pre-2255-g37ef3848bd .

The first recording attempted was 30 mins but only the first minute was
recorded.

Attempting other recordings showed the same problem. This is my production
system so had to revert straight away. There is nothing obvious the system
was looking for the recording to end at the expected time

Feb 12 18:35:00 tv mythbackend: mythbackend[11782]: I TVRecEvent
tv_rec.cpp:823 (FinishedRecording) TVRec[1]:
FinishedRecording(65792_2021-02-12T18:00:00Z) damaged
recq:#012<RecordingQuality overall_score="0"
key="65792_2021-02-12T18:00:00Z" continuity_error_count="0"
packet_count="361427">#012 <Gap start="2021-02-12T18:00:58Z"
end="2021-02-12T18:30:00Z" duration="1741" />#012</RecordingQuality>

Presumably the recent timer changes in Master have affected the sat-ip
recorder.

Note that the input lights on the Digibit appeared to being extinguished
much earlier than normal releasing those inputs. So time may have been
affected in other ways than just recordings. Cannot be sure of this as I
don't fully understand the normal behaviour, will attach log.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:53>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------
Changes (by jksj461):

* Attachment "ShortRecordings.txt" added.


--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

I can confirm that with today's master Sat>IP is failing. Version
86d05b6d2c which is from 29 days ago, so before the std::chrono update, is
still OK.
I will look into this.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:54>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

Fixed in commit 3cf00701ba08469c58f5e7e3b2e88fc38b621cc8.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:55>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Thanks for the quick fix much better but I had a zero byte recording for
the first time ever using sat-ip when twp programs started at the same
time. May be just a coincidence will test more in the morning.

{{{
FebFeb 13 20:59:21 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:405 (timerEvent) SatIPRTSP[1]: Sending KeepAlive
timer 4
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:289 (RecordPending) TVRec[1]: RecordPending on inputid [1]
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
cardutil.cpp:2092 (GetConflictingInputs) CardUtil[1]:
GetConflictingInputs(1) 4
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:289 (RecordPending) TVRec[4]: RecordPending on inputid [1]
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:289 (RecordPending) TVRec[2]: RecordPending on inputid [2]
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
cardutil.cpp:2092 (GetConflictingInputs) CardUtil[2]:
GetConflictingInputs(2) 5
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:289 (RecordPending) TVRec[5]: RecordPending on inputid [2]
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1623 (HandlePendingRecordings) TVRec[1]: ASK_RECORDING 1 29 0 0
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3505 (HandleTuning) TVRec[1]: HandleTuning Request:
Program(NULL) channel() input() flags(NOREC,)
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3574 (TuningShutdowns) TVRec[1]: TuningShutdowns(Program(NULL)
channel() input() flags(NOREC,))
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2097 (TeardownSignalMonitor) TVRec[1]: TeardownSignalMonitor()
-- begin
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/streamhandler.cpp:82 (RemoveListener) SH[1]:
RemoveListener(0x7fc048157858) -- begin
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/streamhandler.cpp:94 (RemoveListener) SH[1]:
RemoveListener(0x7fc048157858) -- locked
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satipstreamhandler.cpp:197 (run) SatIPSH[1]: RunTS(): shutdown
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids) pids=none
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:394 (stopKeepAliveRequested) SatIPRTSP[1]:
stopKeepAliveRequested() m_timer:4
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satipstreamhandler.cpp:210 (run) SatIPSH[1]: RunTS(): end
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/streamhandler.cpp:111 (RemoveListener) SH[1]:
RemoveListener(0x7fc048157858) -- end
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:75 (Return) SatIPSH[1]: Return stream
handler for uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1 (3 users)
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2116 (TeardownSignalMonitor) TVRec[1]: TeardownSignalMonitor()
-- end
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(SignalMonitorRunning,)
-> RunMainLoop,EITScannerRunning,RingBufferReady, @ tv_rec.cpp:3595
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,EITScannerRunning,RingBufferReady, @ tv_rec.cpp:3641
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3527 (HandleTuning) TVRec[1]: No recorder yet, calling
TuningFrequency
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3663 (TuningFrequency) TVRec[1]: TuningFrequency
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: E TVRecEvent
tv_rec.cpp:3736 (TuningFrequency) TVRec[1]: Failed to set channel to .
Reverting to kState_None
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3505 (HandleTuning) TVRec[1]: HandleTuning Request:
Program(NULL) channel() input() flags(KillRec,)
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3574 (TuningShutdowns) TVRec[1]: TuningShutdowns(Program(NULL)
channel() input() flags(KillRec,))
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1623 (HandlePendingRecordings) TVRec[2]: ASK_RECORDING 2 28 0 0
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(EITScannerRunning,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:3581
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
scheduler.cpp:2310 (HandleReschedule) Reschedule requested for MATCH 0 0 0
2021-02-13T21:00:00Z EITScanner
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:3641
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3505 (HandleTuning) TVRec[1]: HandleTuning Request:
Program(NULL) channel() input() flags(NOREC,)
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3574 (TuningShutdowns) TVRec[1]: TuningShutdowns(Program(NULL)
channel() input() flags(NOREC,))
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:3641
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3527 (HandleTuning) TVRec[1]: No recorder yet, calling
TuningFrequency
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3663 (TuningFrequency) TVRec[1]: TuningFrequency
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: E TVRecEvent
tv_rec.cpp:3736 (TuningFrequency) TVRec[1]: Failed to set channel to .
Reverting to kState_None
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3505 (HandleTuning) TVRec[1]: HandleTuning Request:
Program(NULL) channel() input() flags(KillRec,)
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3574 (TuningShutdowns) TVRec[1]: TuningShutdowns(Program(NULL)
channel() input() flags(KillRec,))
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1623 (HandlePendingRecordings) TVRec[4]: ASK_RECORDING 4 29 0 0
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:3641
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1623 (HandlePendingRecordings) TVRec[5]: ASK_RECORDING 5 28 0 0
Feb 13 20:59:30 tv mythbackend: mythbackend[8076]: I Scheduler
scheduler.cpp:2424 (HandleReschedule) Scheduled 74 items in 0.2 = 0.11
match + 0.02 check + 0.10 place
Feb 13 20:59:40 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:405 (timerEvent) SatIPRTSP[5]: Sending KeepAlive
timer 6
Feb 13 20:59:44 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:405 (timerEvent) SatIPRTSP[6]: Sending KeepAlive
timer 8
Feb 13 20:59:47 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:405 (timerEvent) SatIPRTSP[8]: Sending KeepAlive
timer 10
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:402 (StartRecording) TVRec[1]: StartRecording("Line of Duty")
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:689 (SetRecordingStatus) TVRec[1]:
SetRecordingStatus(Recording->Aborted) on line 408
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:471 (StartRecording) TVRec[1]: Checking input group recorders -
begin
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:545 (StartRecording) TVRec[1]: Checking input group recorders -
done
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:791 (StartedRecording) TVRec[1]:
StartedRecording(65792_2021-02-13T21:00:00Z)
fn(/media/library/TV/65792_20210213210000.ts)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:4140 (LoadProfile) TVRec[1]: Using profile 'Default' to record
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(CancelNextRecording,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:590
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:689 (SetRecordingStatus) TVRec[1]:
SetRecordingStatus(Aborted->Tuning) on line 593
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1058 (HandleStateChange) TVRec[1]: Changing from None to
RecordingOnly
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]:
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:1297
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3505 (HandleTuning) TVRec[1]: HandleTuning Request:
Program(ProgramInfo(65792_20210213210000.ts): channame(BBC ONE HD)#012
startts(Sat Feb 13 21:00:00 2021 GMT) endts(Sat Feb 13 22:00:00 2021
GMT)#012 recstartts(Sat Feb 13 21:00:00 2021 GMT) recendts(Sat
Feb 13 22:00:00 2021 GMT)#012 title(Line of Duty)) channel()
input() flags(Recording,)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3574 (TuningShutdowns) TVRec[1]:
TuningShutdowns(Program(ProgramInfo(65792_20210213210000.ts): channame(BBC
ONE HD)#012 startts(Sat Feb 13 21:00:00 2021 GMT) endts(Sat
Feb 13 22:00:00 2021 GMT)#012 recstartts(Sat Feb 13 21:00:00
2021 GMT) recendts(Sat Feb 13 22:00:00 2021 GMT)#012
title(Line of Duty)) channel(101) input(None) flags(Recording,))
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,RingBufferReady, @ tv_rec.cpp:3641
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3527 (HandleTuning) TVRec[1]: No recorder yet, calling
TuningFrequency
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3663 (TuningFrequency) TVRec[1]: TuningFrequency
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
cardutil.cpp:2092 (GetConflictingInputs) CardUtil[1]:
GetConflictingInputs(1) 4
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:217 (Tune) SatIPSH[1]: Tune 10847000
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:266 (Tune) SatIPSH[1]: Tune
url:rtsp://192.168.0.44:554/?fe=2&freq=10847.00&pol=v&ro=0.20&msys=dvbs2&mtype=8psk&sr=23000&fec=34&plts=auto
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids) pids=none
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:388 (startKeepAliveRequested) SatIPRTSP[1]:
startKeepAliveRequested(30000) m_timer:4
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3799 (TuningFrequency) TVRec[1]: Starting Signal Monitor
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2040 (SetupSignalMonitor) TVRec[1]: SetupSignalMonitor(1, 0)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:53 (Get) SatIPSH[1]: Using existing
stream handler for uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1 (3
users)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2064 (SetupSignalMonitor) TVRec[1]: Signal monitor successfully
created
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1860 (SetupDTVSignalMonitor) TVRec[1]: Setting up table
monitoring.
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4140 (LoadProfile) TVRec[1]: Using profile 'Live TV' to record
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1935 (SetupDTVSignalMonitor) TVRec[1]: DVB service_id 6941 on
net_id 2 tsid 2050
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1957 (SetupDTVSignalMonitor) TVRec[1]: Successfully set up DVB
table monitoring.
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[1]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3826
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning,RingBufferReady, @ tv_rec.cpp:3827
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[1]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @
tv_rec.cpp:3832
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady, @
tv_rec.cpp:3893
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[1]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
@ tv_rec.cpp:3895
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4010 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Still
waiting. Will timeout @ 21:59:50.000
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
scheduler.cpp:2877 (HandleRecordingStatusChange) Tuning recording: "Line
of Duty": channel 65792 on cardid [1], sourceid 1
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:402 (StartRecording) TVRec[2]: StartRecording("The Little
Stranger")
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:689 (SetRecordingStatus) TVRec[2]:
SetRecordingStatus(Unknown->Aborted) on line 408
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:471 (StartRecording) TVRec[2]: Checking input group recorders -
begin
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:545 (StartRecording) TVRec[2]: Checking input group recorders -
done
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:791 (StartedRecording) TVRec[2]:
StartedRecording(65888_2021-02-13T21:00:00Z)
fn(/media/library/TV/65888_20210213210000.ts)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:4140 (LoadProfile) TVRec[2]: Using profile 'Default' to record
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:4434 (ClearFlags) TVRec[2]: ClearFlags(CancelNextRecording,) ->
RunMainLoop, @ tv_rec.cpp:590
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
tv_rec.cpp:689 (SetRecordingStatus) TVRec[2]:
SetRecordingStatus(Aborted->Tuning) on line 593
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1058 (HandleStateChange) TVRec[2]: Changing from None to
RecordingOnly
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[2]:
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, @
tv_rec.cpp:1297
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3505 (HandleTuning) TVRec[2]: HandleTuning Request:
Program(ProgramInfo(65888_20210213210000.ts): channame(Channel 4 HD)#012
startts(Sat Feb 13 21:00:00 2021 GMT) endts(Sat Feb 13 23:15:00 2021
GMT)#012 recstartts(Sat Feb 13 21:00:00 2021 GMT) recendts(Sat
Feb 13 23:15:00 2021 GMT)#012 title(The Little Stranger))
channel() input() flags(Recording,)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
mythdbcon.cpp:419 (PurgeIdleConnections) New DB connection, total: 11
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3574 (TuningShutdowns) TVRec[2]:
TuningShutdowns(Program(ProgramInfo(65888_20210213210000.ts):
channame(Channel 4 HD)#012 startts(Sat Feb 13 21:00:00 2021
GMT) endts(Sat Feb 13 23:15:00 2021 GMT)#012 recstartts(Sat
Feb 13 21:00:00 2021 GMT) recendts(Sat Feb 13 23:15:00 2021 GMT)#012
title(The Little Stranger)) channel(104) input(None) flags(Recording,))
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I SignalMonitor
recorders/streamhandler.cpp:40 (AddListener) SH[1]:
AddListener(0x7fc0481650d8) -- begin
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I SignalMonitor
recorders/streamhandler.cpp:52 (AddListener) SH[1]:
AddListener(0x7fc0481650d8) -- locked
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satipstreamhandler.cpp:157 (run) SatIPSH[1]: RunTS(): begin
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I SignalMonitor
recorders/streamhandler.cpp:74 (AddListener) SH[1]:
AddListener(0x7fc0481650d8) -- end
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids)
pids=0,1,16,17,20
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: C CoreContext
programinfo.cpp:252 (ProgramInfo) ProgramInfo(): Failed to find recorded
entry for 0.
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[2]: ClearFlags(PENDINGACTIONS,) ->
RunMainLoop, @ tv_rec.cpp:3641
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3527 (HandleTuning) TVRec[2]: No recorder yet, calling
TuningFrequency
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3663 (TuningFrequency) TVRec[2]: TuningFrequency
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
cardutil.cpp:2092 (GetConflictingInputs) CardUtil[2]:
GetConflictingInputs(2) 5
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:217 (Tune) SatIPSH[5]: Tune 11126500
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:266 (Tune) SatIPSH[5]: Tune
url:rtsp://192.168.0.44:554/?fe=3&freq=11126.50&pol=v&ro=0.35&msys=dvbs&mtype=qpsk&sr=22000&fec=56&plts=auto
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:394 (stopKeepAliveRequested) SatIPRTSP[5]:
stopKeepAliveRequested() m_timer:6
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[5]: Play(pids) pids=none
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:388 (startKeepAliveRequested) SatIPRTSP[5]:
startKeepAliveRequested(30000) m_timer:6
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3799 (TuningFrequency) TVRec[2]: Starting Signal Monitor
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2040 (SetupSignalMonitor) TVRec[2]: SetupSignalMonitor(1, 0)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:53 (Get) SatIPSH[2]: Using existing
stream handler for uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:2 (3
users)
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2064 (SetupSignalMonitor) TVRec[2]: Signal monitor successfully
created
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1860 (SetupDTVSignalMonitor) TVRec[2]: Setting up table
monitoring.
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4140 (LoadProfile) TVRec[2]: Using profile 'Live TV' to record
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1935 (SetupDTVSignalMonitor) TVRec[2]: DVB service_id 21200 on
net_id 2 tsid 2068
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:1957 (SetupDTVSignalMonitor) TVRec[2]: Successfully set up DVB
table monitoring.
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[2]: SetFlags(SignalMonitorRunning,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3826
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[2]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,SignalMonitorRunning, @ tv_rec.cpp:3827
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[2]: SetFlags(WaitingForSignal,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3832
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[2]: ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,SignalMonitorRunning, @ tv_rec.cpp:3893
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[2]: SetFlags(NeedToStartRecorder,) ->
RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning, @
tv_rec.cpp:3895
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4010 (TuningSignalCheck) TVRec[2]: TuningSignalCheck: Still
waiting. Will timeout @ 23:14:50.000
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I Scheduler
scheduler.cpp:2877 (HandleRecordingStatusChange) Tuning recording: "The
Little Stranger": channel 65888 on cardid [2], sourceid 1
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: N Scheduler
scheduler.cpp:3233 (HandleIdleShutdown) Blocking shutdown because of an
active encoder
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: N Scheduler
scheduler.cpp:3244 (HandleIdleShutdown) Blocking shutdown because of delay
request from external application
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:575 (timerEvent) SatIPRTSP[1]: RTP Sequence number
mismatch 50700!=18320, discarded 2 RTP packets
Feb 13 21:00:00 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids)
pids=0,1,16,17,20,192,260
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids)
pids=0,1,16,17,20,192,260,2321,2322,2329,3840,3841,3842,3843,3844,3846,3847,3849,3851,5400,5401,5402,5403,5404
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: E CoreContext
mpeg/pespacket.cpp:93 (AddTSPacket) AddTSPacket[1]: Out of sync!!! Need to
wait for next payloadStart PID: 0x11, continuity counter: 5 (expected 4)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:575 (timerEvent) SatIPRTSP[2]: RTP Sequence number
mismatch 24461!=23211, discarded 1 RTP packets
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:3921 (TuningSignalCheck) TVRec[1]: TuningSignalCheck: Good
signal
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:689 (SetRecordingStatus) TVRec[1]:
SetRecordingStatus(Tuning->Recording) on line 4019
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2097 (TeardownSignalMonitor) TVRec[1]: TeardownSignalMonitor()
-- begin
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
scheduler.cpp:707 (UpdateRecStatus) Updating status for "Line of Duty" on
cardid [1] (Tuning => Recording)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids)
pids=0,1,16,17,18,20,192,260,2321,2322,2329,3840,3841,3842,3843,3844,3846,3847,3849,3851,5400,5401,5402,5403,5404
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/streamhandler.cpp:82 (RemoveListener) SH[1]:
RemoveListener(0x7fc0481650d8) -- begin
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/streamhandler.cpp:94 (RemoveListener) SH[1]:
RemoveListener(0x7fc0481650d8) -- locked
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satipstreamhandler.cpp:197 (run) SatIPSH[1]: RunTS(): shutdown
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids) pids=none
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:394 (stopKeepAliveRequested) SatIPRTSP[1]:
stopKeepAliveRequested() m_timer:4
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satipstreamhandler.cpp:210 (run) SatIPSH[1]: RunTS(): end
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/streamhandler.cpp:111 (RemoveListener) SH[1]:
RemoveListener(0x7fc0481650d8) -- end
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/satipstreamhandler.cpp:75 (Return) SatIPSH[1]: Return stream
handler for uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1 (3 users)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:2116 (TeardownSignalMonitor) TVRec[1]: TeardownSignalMonitor()
-- end
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(SignalMonitorRunning,)
-> RunMainLoop,WaitingForSignal,NeedToStartRecorder,RingBufferReady, @
tv_rec.cpp:4045
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(WaitingForSignal,) ->
RunMainLoop,NeedToStartRecorder,RingBufferReady, @ tv_rec.cpp:4047
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4172 (TuningNewRecorder) TVRec[1]: Starting Recorder
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4140 (LoadProfile) TVRec[1]: Using profile 'Default' to record
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4210 (TuningNewRecorder) TVRec[1]: rec->GetPathname():
'/media/library/TV/65792_20210213210000.ts'
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4243 (TuningNewRecorder) TVRec[1]: TuningNewRecorder -
CreateRecorder()
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/dtvrecorder.cpp:140 (ResetForNewFile) DTVRec[1]:
ResetForNewFile(void)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/recorderbase.cpp:81 (SetRingBuffer)
RecBase[1](uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1):
SetRingBuffer(0x7fc010037200) '/media/library/TV/65792_20210213210000.ts'
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/recorderbase.cpp:92 (SetRecording)
RecBase[1](uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1):
SetRecording(0x7fc010025e30) title(Line of Duty)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
mpeg/mpegstreamdata.cpp:73 (SetDesiredProgram)
MPEGStream[1](0x7fc0481650d8): SetDesiredProgram(6941)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/dtvrecorder.cpp:1281 (HandlePAT) DTVRec[1]: SetPAT(6941 on pid
0x104)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6941, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
recorders/dtvrecorder.cpp:1307 (HandlePMT) DTVRec[1]: SetPMT(6941)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/satiprecorder.cpp:74 (run) SatIPRec[1]: run -- begin
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/dtvrecorder.cpp:140 (ResetForNewFile) DTVRec[1]:
ResetForNewFile(void)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/satipstreamhandler.cpp:53 (Get) SatIPSH[1]: Using existing
stream handler for uuid:3eec0475-c365-44e7-bf7c-6d53b0daf8e4:DVBS2:1 (3
users)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/satiprecorder.cpp:44 (Open) SatIPRec[1]: SatIP opened
successfully
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4425 (SetFlags) TVRec[1]:
SetFlags(RecorderRunning,RingBufferReady,) ->
RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady, @
tv_rec.cpp:4314
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:4434 (ClearFlags) TVRec[1]: ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,RecorderRunning,RingBufferReady, @ tv_rec.cpp:4316
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/streamhandler.cpp:40 (AddListener) SH[1]:
AddListener(0x7fc0481650d8) -- begin
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/streamhandler.cpp:52 (AddListener) SH[1]:
AddListener(0x7fc0481650d8) -- locked
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satipstreamhandler.cpp:157 (run) SatIPSH[1]: RunTS(): begin
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I RecThread
recorders/streamhandler.cpp:74 (AddListener) SH[1]:
AddListener(0x7fc0481650d8) -- end
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:388 (startKeepAliveRequested) SatIPRTSP[1]:
startKeepAliveRequested(30000) m_timer:4
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:184 (Play) SatIPRTSP[1]: Play(pids)
pids=0,1,16,17,18,20,192,256,257,258,259,260,262,263,264,265,266,2321,2322,2329,3840,3841,3842,3843,3844,3846,3847,3849,3851,5400,5401,5402,5403,5404
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I SatIPStreamHandler
recorders/satiprtsp.cpp:189 (Play) SatIPRTSP[1]: Receive full TS, number
of PIDs:34 is more than 32
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:575 (timerEvent) SatIPRTSP[1]: RTP Sequence number
mismatch 34707!=51044, discarded 95 RTP packets
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: E CoreContext
mpeg/pespacket.cpp:93 (AddTSPacket) AddTSPacket[1]: Out of sync!!! Need to
wait for next payloadStart PID: 0x104, continuity counter: 12 (expected 8)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6963, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6943, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6945, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6912, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6961, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6940, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6960, valid)
Feb 13 21:00:01 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6972, valid)
Feb 13 21:00:02 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1301 (HandlePMT) DTVRec[1]: SetPMT(6952, valid)
Feb 13 21:00:02 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1018 (FindH2645Keyframes) DTVRec[1]:
FindH2645Keyframes: timescale: 50, tick: 1, framerate: 25000
Feb 13 21:00:02 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1631 (ProcessAVTSPacket) DTVRec[1]: PID 0x1518
Found Payload Start
Feb 13 21:00:02 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1631 (ProcessAVTSPacket) DTVRec[1]: PID 0x1519
Found Payload Start
Feb 13 21:00:02 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/dtvrecorder.cpp:1631 (ProcessAVTSPacket) DTVRec[1]: PID 0x151a
Found Payload Start
Feb 13 21:00:03 tv mythbackend: mythbackend[8076]: E TVRecEvent
tv_rec.cpp:3968 (TuningSignalCheck) TVRec[2]: TuningSignalCheck: Hit pre-
fail timeout
Feb 13 21:00:04 tv mythbackend: mythbackend[8076]: W TVRecEvent
tv_rec.cpp:3996 (TuningSignalCheck) TVRec[2]: TuningSignalCheck: taking
more than 3000 ms to get a lock. marking this recording as 'Failing'.
Feb 13 21:00:04 tv mythbackend: mythbackend[8076]: W TVRecEvent
tv_rec.cpp:4001 (TuningSignalCheck) TVRec[2]: See 'Tuning timeout' in
mythtv-setup for this input
Feb 13 21:00:04 tv mythbackend: mythbackend[8076]: I TVRecEvent
tv_rec.cpp:689 (SetRecordingStatus) TVRec[2]:
SetRecordingStatus(Tuning->Failing) on line 4019
Feb 13 21:00:04 tv mythbackend: mythbackend[8076]: I CoreContext
scheduler.cpp:707 (UpdateRecStatus) Updating status for "The Little
Stranger" on cardid [2] (Tuning => Failing)
Feb 13 21:00:04 tv mythbackend: mythbackend[8076]: I Scheduler
scheduler.cpp:2310 (HandleReschedule) Reschedule requested for CHECK -14
7582 0 UpdateRecStatus2 | The Little Stranger | | Post-war gothic drama
starring Ruth Wilson and Domhnall Gleeson. When a doctor is called to a
mansion in the village where he grew up, it's very different from what he
imagined... | MV181627698
Feb 13 21:00:04 tv mythbackend: mythbackend[8076]: I Scheduler
scheduler.cpp:2424 (HandleReschedule) Scheduled 74 items in 0.1 = 0.00
match + 0.00 check + 0.10 place
}}}

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:56>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------
Changes (by jksj461):

* Attachment "debug.txt" added.

Failed Recording noting short timeout

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

Done some testing, 3 times two recordings starting at the same time, and
they are all good.

Your failing recordings seem to be on TVRec[2], so the second tuner. Maybe
this tuner currently does not work at all anymore. It could just be that
everything is OK again after a reboot of the Digibit. Can you please try
that?

Not really relevant to your recording problem, but your log format is
rather different from mine. This is from your log:
{{{
Feb 13 20:59:40 tv mythbackend: mythbackend[8076]: I CoreContext
recorders/satiprtsp.cpp:405 (timerEvent) SatIPRTSP[5]: Sending KeepAlive
timer 6

}}}


This is a similar message from my log:
{{{
2021-02-14 14:07:01.713803 I [380058/380058] CoreContext
recorders/satiprtsp.cpp:405 (timerEvent) - SatIPRTSP[11]: Sending
KeepAlive timer 2
}}}
And this also with the latest, c64556b924.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:57>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by David Hampton):

That looks like the difference between logging to syslog/systemd and
logging to a logfile.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:58>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by jksj461):

Zero byte recording:-
Seems to have been a one off, I have been testing and cannot repeat the
issue, so many thanks.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:59>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by fe31nz):

I have been recording SAT>IP with MythTV for several years now by manually
putting URLs into the database using IPTV (FREEBOX) tuners as per
https://www.mythtv.org/wiki/SAT2IP_players_as_capture_cards.

It is good to see real SAT>IP support arriving for v32, and I thought I
had better test it to make sure it was going to work for me. I have set
up a new PC using the old motherboard from my mother's MythTV box, put
Xubuntu 20.04 on it and installed v32 from the PPA.

For my current production system I am using minisatip on the same box as
my main MythTV system, which has a TBS6909 8 tuner DVB-S2 card and a
TBS6209 8 tuner DVB-T2 card. Mythbackend directly uses 5 of the TBS6209
DVB-T2 tuners for the 5 DVB-T muxes available here in New Zealand, and my
MHEG5 EPG gathering uses one more of the DVB-T2 tuners, leaving two DVB-T2
tuners for minisatip. One of the TBS6909 DVB-S2 tuners is used for OpenTV
format EPG gathering and the other 7 DVB-S2 tuners are used by minisatip.
Minisatip uses my Sky NZ satellite card to decrypt my pay TV channels via
Oscam. My mother's MythTV box also connects to minisatip to record Sky NZ
channels.

On the new test PC, I created two sources, one for DVB-T and one for
DVB-S2. I created two SAT>IP tuners, one for each source, and tried
channel scanning. Scanning on the DVB-T tuner worked fine and I was able
to record from the channels it put in the database. However, when I try
to scan using the DVB-S2 tuner, I immediately get a popup error message
"Error parsing parameters". I used Wireshark to check for traffic to
minisatip, and the error was occurring before any packets were sent. I
tried a number of different combinations of scan settings to see if that
would help, but I got the same error each time. Then I deleted all
sources and tuners and started again with only one source for DVB-S2 and
one DVB-S2 SAT>IP tuner. But I got exactly the same error message.

MythTV Version : v32.0~master.202102151820.e787645fd9~ubuntu20.04.1
MythTV Branch : master
Network Protocol : 91
Library API : 32.20200101-1
QT Version : 5.12.8
Options compiled in:
linux profile use_hidesyms using_alsa using_oss using_pulse
using_pulseoutput using_backend using_bindings_perl using_bindings_python
using_bindings_php using_dvb using_firewire using_frontend using_hdhomerun
using_satip using_vbox using_ceton using_hdpvr using_ivtv
using_joystick_menu using_libcec using_libcrypto using_gnutls
using_libdns_sd using_libfftw3 using_libxml2 using_lirc using_mheg
using_opengl using_egl using_qtwebkit using_qtscript using_qtdbus
using_taglib using_v4l2 using_v4l2prime using_x11 using_libbluray_external
using_profiletype using_systemd_notify using_systemd_journal using_drm
using_bindings_perl using_bindings_python using_bindings_php
using_freetype2 using_mythtranscode using_opengl using_egl using_drm
using_vaapi using_nvdec using_vdpau using_ffmpeg_threads using_mheg
using_libass using_libxml2 using_libmp3lame

Klaas: If it is any use for your testing, I should be able to make my
minisatip available for Internet connections - but that would require that
connections came from static IP addresses that I could whitelist in my
router. Leaving completely open ports to minisatip would not be a good
idea. I have a fibre connection with 1 Gbit/s downstream and 500 Mbit/s
upstream and my ISP's international bandwidth is good.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:60>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

I might just take you up on that offer when things get difficult...

But first check the setup for the channelscan.
The error message indicates that the tuning parameters are not filled in.
Configuring his is the same for Sat>IP and for conventional satellite
tuners, but it is different from how it used to be a few MythTV versions
ago and it is not as user-friendly as it could be.

When you are in the Channel Scan page you have to select Scan Type.
Select Full Scan (Tuned). This requires that you know the tuning
parameters for the first multiplex.
Press **right arrow** to get to the window to fill the tuning parameters,
as described here:
https://www.mythtv.org/wiki/Channel_Scanning#Full_Scan_.28Tuned.29
Press the **left arrow** to get back to the Channel Scan page.
Then one choice down to the Scan button and do the scan.
This should now work.

If you are happy with the scan then put the frequency of the initial
transport in the Video Source. This frequency, and the tuning parameters
of that transport, will then be the default the next time you do a Full
Scan (Tuned).

If this fails then please post the command sequence/configuration/version
for minisatip so that I can try to reproduce the problem.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:61>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

Sat>IP is not stable with today's master. It is a bit difficult to test as
it can run OK for one or two days before failing. This could be a bug that
was always there or it could be caused by another update in the last few
months. To be investigaged.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:62>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by Klaas de Waal):

Sat>IP fails repeatable with the following sequence:
- recording in progress, starting at 05:00 and scheduled to end at 06:00
- nightly database backup starts at 05:30
- the recording ends as "Bad quality" with a gap starting at 05:32 and
ending at 06:00
- the continuity_error_count is 0 so the recording from 05:00 to 05:32 is
perfect
After the recording has failed the RTP communication path is completely
dead. The RTCP communication seems to be still working. A restart of the
backend is enough to make it working again; the Sat>IP box does not need
to be reset or rebooted.
Current hypothesis is:
- the database backup causes the Sat>IP to fail
- possible causes can be CPU starvation, disk I/O starvation or database
locking
- the actual problem is likely to be in the RTP part.
As a workaround I recommend to avoid scheduling recordings together with
database backups or even any other kind of backups or heavy CPU load.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:63>
MythTV <http://www.mythtv.org>
MythTV Media Center

#13121: Sat>IP client support
--------------------------------+-------------------------------
Reporter: cg@… | Owner: Klaas de Waal
Type: Patch - Feature | Status: assigned
Priority: minor | Milestone: 32.0
Component: MythTV - Recording | Version: Master Head
Severity: low | Resolution:
Keywords: | Ticket locked: 0
--------------------------------+-------------------------------

Comment (by fe31nz):

In my MythTV 0.31-fixes system where I record from SAT>IP using IPTV URLs,
I have a number of recordings (7 this year so far) that were done at the
same time that I have my daily anacron jobs running (which include my
MythTV database check and backup jobs). They seem to have recorded
without any problems. This method of recording uses URLs like this in
iptv_channel:

rtsp://satip.jsw.gen.nz:554/?src=1&freq=12546&msys=dvbs2&plts=off&fec=23&pol=h&ro=0.35&sr=22500&mtype=8psk&pids=0,311,1011,1111,1911

and appears to use the RTP/RTSP code that was originally create for Ceton
tuners. So if the new SAT>IP code is using the same RTP/RTSP code, then
it should also be able to record through the database checking and
backups. My database is extremely large - the last backup .tar.gz file is
2,994,561,822 bytes. The checking and backup of it is very intensive work
and only happens in a reasonable time due to my running it on a very fast
NVMe SSD. Howvever, to prevent problems at some time in the past I
altered my /etc/cron.daily/mythtv-database file so that it runs
mythconverg_backup.pl at lower priority (nice -n 2), and I run a modified
version mythconverg_backup.pl that puts its temporary file (before
compression) on the same NVMe SSD instead of on the ultimate destination
drive for the backup file (a network drive on my Windows box).

If you want to try them, I have put my /etc/cron.daily/mythtv-database and
/usr/local/bin/mythconverg_backup_jsw.pl files on my web server:

http://www.jsw.gen.nz/mythtv/mythtv-database

http://www.jsw.gen.nz/mythtv/mythconverg_backup_jsw.pl

--
Ticket URL: <https://code.mythtv.org/trac/ticket/13121#comment:64>
MythTV <http://www.mythtv.org>
MythTV Media Center