Tvheadend

SOLVED!!! Not TVH problem. ITV have removed some SD regional versions on Freesat. Beware!!!!!!!!

I've never seen this before...

TVH 4.2.8-36 running on Raspberry Pi 4, Raspbios Buster, Unix kernel 5.4.83-v7l+ #1379 SMP Mon Dec 14 13:11:54 GMT 2020 armv7l GNU/Linux

I had several recordings set up for today. Most of them completed fine. But one generated a TS file that was only 3 MB (!). I've got TVH configured to call, as a postproc action, a C program that I have written which dumps the program name, episode summary etc to a text file (using the TVH command line /home/pi/postproc "%f" "%b" "%t" "%s" "%e" "%S" "%E"). And the output in that file for the rogue recording is fine, showing that my postproc was called when recording stopped at the expected time (published time + 10 minutes padding).

There were no overlapping recordings. All the recordings used the default (highest priority) tuner "PCTV 491e (DVB-S2) (Montage Technology M88DS3103)" as expected.

The relevant syslog lines (including non-TVH info just in case it proves to be relevant) are given below.

The successful recordings are "Last Word", 1555 (incl 5 min pre-padding) to 1640 (incl 10 min post-padding) and "Churchill", 2055-2210. The failed recording (TS file size 3 MB) was "Calendar", 1755-1840. (Note that Last Word is a recording from a radio channel, so there will be no video-related info!)

When I analysed the resulting .ts file using Windows program MediaInfo, it was correctly identified as type MPEG-TS, but had no bitrate or duration listed. I can attach the .ts file if it's helpful.

Had anyone got any suggestions? Is it likely to be a one-off (eg a reception glitch)? I've made well over a hundred successful recordings with the hardware/software configuration, and never seen this symptom before. There was no power cut - I checked "uptime" ;-)

(/var/log/syslog extract)
Apr 16 15:54:30 martin-pi4 tvheadend⁵⁴⁴: dvr: "Last Word" on "704 BBC Radio 4" recorder starting
Apr 16 15:54:30 martin-pi4 tvheadend⁵⁴⁴: dvr: About to set stop timer for "Last Word" on "704 BBC Radio 4" at start 1618585200 and original stop 1618587000 and overall stop at 1618587600
Apr 16 15:54:30 martin-pi4 tvheadend⁵⁴⁴: mpegts: 10788V in PCTV 461e (Sat) - tuning on PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2
Apr 16 15:54:30 martin-pi4 tvheadend⁵⁴⁴: subscription: 00EC: "DVR: Last Word" subscribing on channel "704 BBC Radio 4", weight: 300, adapter: "PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2", network: "PCTV 461e (Sat)", mux: "10788V", provider: "BSkyB", service: "BBC R4 FM", profile="pass"
Apr 16 15:54:30 martin-pi4 tvheadend⁵⁴⁴: linuxdvb: Unable to provide UNC value.
Apr 16 15:55:00 martin-pi4 tvheadend⁵⁴⁴: pass: /home/pi/ext/Videos/Last Word/Last Word-20210416-1600-704 BBC Radio 4.ts: Unable to change permissions -- Operation not permitted
Apr 16 15:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: /home/pi/ext/Videos/Last Word/Last Word-20210416-1600-704 BBC Radio 4.ts from adapter: "PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2", network: "PCTV 461e (Sat)", mux: "10788V", provider: "BSkyB", service: "BBC R4 FM"
Apr 16 15:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: # type lang resolution aspect ratio sample rate channels
Apr 16 15:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 1 MPEG2AUDIO eng ? ?
Apr 16 15:55:40 martin-pi4 tvheadend⁵⁴⁴: epggrab: EIT: DVB Grabber - data completion timeout for 10788V in PCTV 461e (Sat)
Apr 16 15:55:40 martin-pi4 tvheadend⁵⁴⁴: epggrab: UK: Freesat - data completion timeout for 10788V in PCTV 461e (Sat)
Apr 16 16:17:01 martin-pi4 CRON¹⁶⁵⁴¹: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: snapshot start
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: queued to save (size 22140328)
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: brands 0
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: seasons 5821
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: episodes 18349
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: broadcasts 37712
Apr 16 16:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: save start
Apr 16 16:21:09 martin-pi4 tvheadend⁵⁴⁴: epgdb: stored (size 4347294)
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: bits received from HRNG source: 1400064
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: bits sent to kernel pool: 1352288
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: entropy added to kernel pool: 1352288
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 successes: 70
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 failures: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: HRNG source speed: (min=138.565; avg=446.309; max=494.550)Kibits/s
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS tests speed: (min=1.218; avg=12.049; max=18.847)Mibits/s
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: Lowest ready-buffers level: 2
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: Entropy starvations: 0
Apr 16 16:21:27 martin-pi4 rngd³⁸⁸: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: subscription: 00EC: "DVR: Last Word" unsubscribing from "704 BBC Radio 4"
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: Executing "/home/pi/postproc"
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: dvr: "Last Word" on "704 BBC Radio 4": End of program: Completed OK
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: Recording finished at Fri 16 Apr 2021 16:40:00
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argc =#011#0118
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv¹ =#011/home/pi/ext/Videos/Last Word/Last Word-20210416-1600-704 BBC Radio 4.ts
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv² =#011Last Word-20210416-1600-704 BBC Radio 4.ts
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv³ =#011Last Word
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁴ =#011Matthew Bannister on politician Baroness Williams, Nasa flight director Glynn Lunney and historian Lyn Macdonald.
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁵ =#011OK
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁶ =#011Fri 16 Apr 2021 15:55:00 (1618584900)
Apr 16 16:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁷ =#011Fri 16 Apr 2021 16:40:00 (1618587600)
Apr 16 17:17:01 martin-pi4 CRON²⁴⁰⁴⁷: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: snapshot start
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: queued to save (size 22045514)
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: brands 0
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: seasons 5805
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: save start
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: episodes 18296
Apr 16 17:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: broadcasts 37540
Apr 16 17:21:09 martin-pi4 tvheadend⁵⁴⁴: epgdb: stored (size 4326655)
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: bits received from HRNG source: 1440064
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: bits sent to kernel pool: 1382048
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: entropy added to kernel pool: 1382048
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 successes: 72
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 failures: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: HRNG source speed: (min=138.565; avg=447.036; max=494.550)Kibits/s
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS tests speed: (min=1.218; avg=12.100; max=18.847)Mibits/s
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: Lowest ready-buffers level: 2
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: Entropy starvations: 0
Apr 16 17:21:27 martin-pi4 rngd³⁸⁸: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us

Apr 16 17:54:30 martin-pi4 tvheadend⁵⁴⁴: dvr: "ITV News Calendar" on "003 ITV" recorder starting
Apr 16 17:54:30 martin-pi4 tvheadend⁵⁴⁴: dvr: About to set stop timer for "ITV News Calendar" on "003 ITV" at start 1618592400 and original stop 1618594200 and overall stop at 1618594800
Apr 16 17:54:30 martin-pi4 tvheadend⁵⁴⁴: mpegts: 10891H in PCTV 461e (Sat) - tuning on PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2
Apr 16 17:54:30 martin-pi4 tvheadend⁵⁴⁴: subscription: 00ED: "DVR: ITV News Calendar" subscribing on channel "003 ITV", weight: 300, adapter: "PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2", network: "PCTV 461e (Sat)", mux: "10891H", provider: "BSkyB", service: "ITV", profile="pass"
Apr 16 17:54:30 martin-pi4 tvheadend⁵⁴⁴: linuxdvb: Unable to provide UNC value.
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: pass: /home/pi/ext/Videos/ITV News Calendar/ITV News Calendar-20210416-1800-003 ITV.ts: Unable to change permissions -- Operation not permitted
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: /home/pi/ext/Videos/ITV News Calendar/ITV News Calendar-20210416-1800-003 ITV.ts from adapter: "PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2", network: "PCTV 461e (Sat)", mux: "10891H", provider: "BSkyB", service: "ITV"
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: # type lang resolution aspect ratio sample rate channels
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 1 MPEG2VIDEO ? ?
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 2 MPEG2AUDIO nar ? ?
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 3 MPEG2AUDIO eng ? ?
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 4 DVBSUB eng
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 5 TELETEXT
Apr 16 17:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 6 TEXTSUB eng
Apr 16 17:55:40 martin-pi4 tvheadend⁵⁴⁴: epggrab: EIT: DVB Grabber - data completion timeout for 10891H in PCTV 461e (Sat)
Apr 16 17:55:40 martin-pi4 tvheadend⁵⁴⁴: epggrab: UK: Freesat - data completion timeout for 10891H in PCTV 461e (Sat)
Apr 16 18:17:01 martin-pi4 CRON³¹⁵⁴⁵: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: snapshot start
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: queued to save (size 21949634)
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: brands 0
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: seasons 5801
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: episodes 18231
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: broadcasts 37366
Apr 16 18:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: save start
Apr 16 18:21:09 martin-pi4 tvheadend⁵⁴⁴: epgdb: stored (size 4307038)
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: bits received from HRNG source: 1460064
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: bits sent to kernel pool: 1412288
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: entropy added to kernel pool: 1412288
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 successes: 73
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 failures: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: HRNG source speed: (min=138.565; avg=447.254; max=494.550)Kibits/s
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS tests speed: (min=1.218; avg=12.107; max=18.847)Mibits/s
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: Lowest ready-buffers level: 2
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: Entropy starvations: 0
Apr 16 18:21:27 martin-pi4 rngd³⁸⁸: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: subscription: 00ED: "DVR: ITV News Calendar" unsubscribing from "003 ITV"
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: Executing "/home/pi/postproc"
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: Recording finished at Fri 16 Apr 2021 18:40:00
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argc =#011#0118
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv¹ =#011/home/pi/ext/Videos/ITV News Calendar/ITV News Calendar-20210416-1800-003 ITV.ts
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv² =#011ITV News Calendar-20210416-1800-003 ITV.ts
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv³ =#011ITV News Calendar
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁴ =#011The latest news from around the region with interviews, features and a local weather forecast. [S]
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁵ =#011OK
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁶ =#011Fri 16 Apr 2021 17:55:00 (1618592100)
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁷ =#011Fri 16 Apr 2021 18:40:00 (1618594800)
Apr 16 18:40:00 martin-pi4 tvheadend⁵⁴⁴: dvr: "ITV News Calendar" on "003 ITV": End of program: Completed OK
Apr 16 19:17:01 martin-pi4 CRON⁶⁷⁰⁷: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: snapshot start
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: queued to save (size 21828323)
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: brands 0
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: seasons 5797
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: episodes 18138
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: save start
Apr 16 19:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: broadcasts 37146
Apr 16 19:21:09 martin-pi4 tvheadend⁵⁴⁴: epgdb: stored (size 4288124)
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: bits received from HRNG source: 1500064
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: bits sent to kernel pool: 1442048
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: entropy added to kernel pool: 1442048
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 successes: 75
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 failures: 0
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: HRNG source speed: (min=138.565; avg=448.267; max=494.550)Kibits/s
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS tests speed: (min=1.218; avg=12.178; max=18.847)Mibits/s
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: Lowest ready-buffers level: 2
Apr 16 19:21:27 martin-pi4 rngd³⁸⁸: stats: Entropy starvations: 0
Apr 16 20:21:27 martin-pi4 rngd³⁸⁸: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us

Apr 16 20:54:30 martin-pi4 tvheadend⁵⁴⁴: dvr: "New: Churchill Path to Victory" on "005 Channel 5" recorder starting
Apr 16 20:54:30 martin-pi4 tvheadend⁵⁴⁴: dvr: About to set stop timer for "New: Churchill Path to Victory" on "005 Channel 5" at start 1618603200 and original stop 1618606800 and overall stop at 1618607400
Apr 16 20:54:30 martin-pi4 tvheadend⁵⁴⁴: mpegts: 10964H in PCTV 461e (Sat) - tuning on PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2
Apr 16 20:54:30 martin-pi4 tvheadend⁵⁴⁴: subscription: 00EE: "DVR: New: Churchill Path to Victory" subscribing on channel "005 Channel 5", weight: 300, adapter: "PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2", network: "PCTV 461e (Sat)", mux: "10964H", provider: "BSkyB", service: "Channel 5", profile="pass"
Apr 16 20:54:30 martin-pi4 tvheadend⁵⁴⁴: linuxdvb: Unable to provide UNC value.
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: pass: /home/pi/ext/Videos/New_ Churchill Path to Victory/New_ Churchill Path to Victory-20210416-2100-005 Channel 5.ts: Unable to change permissions -- Operation not permitted
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: /home/pi/ext/Videos/New_ Churchill Path to Victory/New_ Churchill Path to Victory-20210416-2100-005 Channel 5.ts from adapter: "PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2", network: "PCTV 461e (Sat)", mux: "10964H", provider: "BSkyB", service: "Channel 5"
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: # type lang resolution aspect ratio sample rate channels
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 1 MPEG2VIDEO ? ?
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 2 MPEG2AUDIO nar ? ?
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 3 MPEG2AUDIO eng ? ?
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 4 DVBSUB eng
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 5 TELETEXT
Apr 16 20:55:00 martin-pi4 tvheadend⁵⁴⁴: dvr: 6 TEXTSUB eng
Apr 16 20:55:40 martin-pi4 tvheadend⁵⁴⁴: epggrab: UK: Freesat - data completion timeout for 10964H in PCTV 461e (Sat)
Apr 16 20:55:40 martin-pi4 tvheadend⁵⁴⁴: epggrab: EIT: DVB Grabber - data completion timeout for 10964H in PCTV 461e (Sat)
Apr 16 21:17:01 martin-pi4 CRON²¹⁷¹¹: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: snapshot start
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: queued to save (size 21644322)
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: brands 0
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: seasons 5781
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: episodes 18024
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: broadcasts 36806
Apr 16 21:21:08 martin-pi4 tvheadend⁵⁴⁴: epgdb: save start
Apr 16 21:21:09 martin-pi4 tvheadend⁵⁴⁴: epgdb: stored (size 4250351)
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: bits received from HRNG source: 1560064
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: bits sent to kernel pool: 1502048
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: entropy added to kernel pool: 1502048
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 successes: 78
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2 failures: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: HRNG source speed: (min=138.565; avg=449.706; max=494.550)Kibits/s
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: FIPS tests speed: (min=1.218; avg=12.305; max=18.847)Mibits/s
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: Lowest ready-buffers level: 2
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: Entropy starvations: 0
Apr 16 21:21:27 martin-pi4 rngd³⁸⁸: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 21:41:24 martin-pi4 vncserver-x11[498,root]: Connections: connected: 10.120.1.70::64323 (TCP)
Apr 16 21:41:28 martin-pi4 vncserver-x11[498,root]: Connections: authenticated: 10.120.1.70::64323 (TCP), as pi (f permissions)
Apr 16 21:41:29 martin-pi4 colord¹⁰²⁸: failed to get session [pid 23421]: No data available
Apr 16 21:41:53 martin-pi4 kernel: [181236.522008] v3d fec00000.v3d: MMU error from client L2T (0) at 0x4081000, pte invalid
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: subscription: 00EE: "DVR: New: Churchill Path to Victory" unsubscribing from "005 Channel 5"
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: Executing "/home/pi/postproc"
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: dvr: "New: Churchill Path to Victory" on "005 Channel 5": End of program: Completed OK
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: Recording finished at Fri 16 Apr 2021 22:10:00
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argc =#011#0118
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv¹ =#011/home/pi/ext/Videos/New_ Churchill Path to Victory/New_ Churchill Path to Victory-20210416-2100-005 Channel 5.ts
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv² =#011New_ Churchill Path to Victory-20210416-2100-005 Channel 5.ts
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv³ =#011New: Churchill Path to Victory
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁴ =#011Series about the life of the celebrated wartime leader. This episode looks at Churchill's Christmas visit to Washington, DC in 1941, during which he secured the support of the US. (S1 Ep 4)[S]
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁵ =#011OK
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁶ =#011Fri 16 Apr 2021 20:55:00 (1618602900)
Apr 16 22:10:00 martin-pi4 tvheadend⁵⁴⁴: spawn: argv⁷ =#011Fri 16 Apr 2021 22:10:00 (1618607400)
Apr 16 22:10:13 martin-pi4 systemd¹: Starting Daily apt download activities...
Apr 16 22:10:15 martin-pi4 systemd¹: apt-daily.service: Succeeded.
Apr 16 22:10:15 martin-pi4 systemd¹: Started Daily apt download activities.