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[544]: dvr: "Last Word" on "704 BBC Radio 4" recorder starting
Apr 16 15:54:30 martin-pi4 tvheadend[544]: 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[544]: mpegts: 10788V in PCTV 461e (Sat) - tuning on PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2
Apr 16 15:54:30 martin-pi4 tvheadend[544]: 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[544]: linuxdvb: Unable to provide UNC value.
Apr 16 15:55:00 martin-pi4 tvheadend[544]: 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[544]: 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[544]: dvr: # type lang resolution aspect ratio sample rate channels
Apr 16 15:55:00 martin-pi4 tvheadend[544]: dvr: 1 MPEG2AUDIO eng ? ?
Apr 16 15:55:40 martin-pi4 tvheadend[544]: epggrab: EIT: DVB Grabber - data completion timeout for 10788V in PCTV 461e (Sat)
Apr 16 15:55:40 martin-pi4 tvheadend[544]: epggrab: UK: Freesat - data completion timeout for 10788V in PCTV 461e (Sat)
Apr 16 16:17:01 martin-pi4 CRON[16541]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: snapshot start
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: queued to save (size 22140328)
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: brands 0
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: seasons 5821
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: episodes 18349
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: broadcasts 37712
Apr 16 16:21:08 martin-pi4 tvheadend[544]: epgdb: save start
Apr 16 16:21:09 martin-pi4 tvheadend[544]: epgdb: stored (size 4347294)
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: bits received from HRNG source: 1400064
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: bits sent to kernel pool: 1352288
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: entropy added to kernel pool: 1352288
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 successes: 70
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 failures: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: HRNG source speed: (min=138.565; avg=446.309; max=494.550)Kibits/s
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: FIPS tests speed: (min=1.218; avg=12.049; max=18.847)Mibits/s
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: Lowest ready-buffers level: 2
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: Entropy starvations: 0
Apr 16 16:21:27 martin-pi4 rngd[388]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 16:40:00 martin-pi4 tvheadend[544]: subscription: 00EC: "DVR: Last Word" unsubscribing from "704 BBC Radio 4"
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: Executing "/home/pi/postproc"
Apr 16 16:40:00 martin-pi4 tvheadend[544]: dvr: "Last Word" on "704 BBC Radio 4": End of program: Completed OK
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: Recording finished at Fri 16 Apr 2021 16:40:00
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argc =#011#0118
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[1] =#011/home/pi/ext/Videos/Last Word/Last Word-20210416-1600-704 BBC Radio 4.ts
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[2] =#011Last Word-20210416-1600-704 BBC Radio 4.ts
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[3] =#011Last Word
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[4] =#011Matthew Bannister on politician Baroness Williams, Nasa flight director Glynn Lunney and historian Lyn Macdonald.
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[5] =#011OK
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[6] =#011Fri 16 Apr 2021 15:55:00 (1618584900)
Apr 16 16:40:00 martin-pi4 tvheadend[544]: spawn: argv[7] =#011Fri 16 Apr 2021 16:40:00 (1618587600)
Apr 16 17:17:01 martin-pi4 CRON[24047]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: snapshot start
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: queued to save (size 22045514)
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: brands 0
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: seasons 5805
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: save start
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: episodes 18296
Apr 16 17:21:08 martin-pi4 tvheadend[544]: epgdb: broadcasts 37540
Apr 16 17:21:09 martin-pi4 tvheadend[544]: epgdb: stored (size 4326655)
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: bits received from HRNG source: 1440064
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: bits sent to kernel pool: 1382048
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: entropy added to kernel pool: 1382048
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 successes: 72
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 failures: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: HRNG source speed: (min=138.565; avg=447.036; max=494.550)Kibits/s
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: FIPS tests speed: (min=1.218; avg=12.100; max=18.847)Mibits/s
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: Lowest ready-buffers level: 2
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: Entropy starvations: 0
Apr 16 17:21:27 martin-pi4 rngd[388]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 17:54:30 martin-pi4 tvheadend[544]: dvr: "ITV News Calendar" on "003 ITV" recorder starting
Apr 16 17:54:30 martin-pi4 tvheadend[544]: 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[544]: mpegts: 10891H in PCTV 461e (Sat) - tuning on PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2
Apr 16 17:54:30 martin-pi4 tvheadend[544]: 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[544]: linuxdvb: Unable to provide UNC value.
Apr 16 17:55:00 martin-pi4 tvheadend[544]: 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[544]: 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[544]: dvr: # type lang resolution aspect ratio sample rate channels
Apr 16 17:55:00 martin-pi4 tvheadend[544]: dvr: 1 MPEG2VIDEO ? ?
Apr 16 17:55:00 martin-pi4 tvheadend[544]: dvr: 2 MPEG2AUDIO nar ? ?
Apr 16 17:55:00 martin-pi4 tvheadend[544]: dvr: 3 MPEG2AUDIO eng ? ?
Apr 16 17:55:00 martin-pi4 tvheadend[544]: dvr: 4 DVBSUB eng
Apr 16 17:55:00 martin-pi4 tvheadend[544]: dvr: 5 TELETEXT
Apr 16 17:55:00 martin-pi4 tvheadend[544]: dvr: 6 TEXTSUB eng
Apr 16 17:55:40 martin-pi4 tvheadend[544]: epggrab: EIT: DVB Grabber - data completion timeout for 10891H in PCTV 461e (Sat)
Apr 16 17:55:40 martin-pi4 tvheadend[544]: epggrab: UK: Freesat - data completion timeout for 10891H in PCTV 461e (Sat)
Apr 16 18:17:01 martin-pi4 CRON[31545]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: snapshot start
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: queued to save (size 21949634)
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: brands 0
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: seasons 5801
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: episodes 18231
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: broadcasts 37366
Apr 16 18:21:08 martin-pi4 tvheadend[544]: epgdb: save start
Apr 16 18:21:09 martin-pi4 tvheadend[544]: epgdb: stored (size 4307038)
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: bits received from HRNG source: 1460064
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: bits sent to kernel pool: 1412288
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: entropy added to kernel pool: 1412288
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 successes: 73
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 failures: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: HRNG source speed: (min=138.565; avg=447.254; max=494.550)Kibits/s
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: FIPS tests speed: (min=1.218; avg=12.107; max=18.847)Mibits/s
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: Lowest ready-buffers level: 2
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: Entropy starvations: 0
Apr 16 18:21:27 martin-pi4 rngd[388]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 18:40:00 martin-pi4 tvheadend[544]: subscription: 00ED: "DVR: ITV News Calendar" unsubscribing from "003 ITV"
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: Executing "/home/pi/postproc"
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: Recording finished at Fri 16 Apr 2021 18:40:00
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argc =#011#0118
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[1] =#011/home/pi/ext/Videos/ITV News Calendar/ITV News Calendar-20210416-1800-003 ITV.ts
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[2] =#011ITV News Calendar-20210416-1800-003 ITV.ts
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[3] =#011ITV News Calendar
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[4] =#011The latest news from around the region with interviews, features and a local weather forecast. [S]
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[5] =#011OK
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[6] =#011Fri 16 Apr 2021 17:55:00 (1618592100)
Apr 16 18:40:00 martin-pi4 tvheadend[544]: spawn: argv[7] =#011Fri 16 Apr 2021 18:40:00 (1618594800)
Apr 16 18:40:00 martin-pi4 tvheadend[544]: dvr: "ITV News Calendar" on "003 ITV": End of program: Completed OK
Apr 16 19:17:01 martin-pi4 CRON[6707]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: snapshot start
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: queued to save (size 21828323)
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: brands 0
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: seasons 5797
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: episodes 18138
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: save start
Apr 16 19:21:08 martin-pi4 tvheadend[544]: epgdb: broadcasts 37146
Apr 16 19:21:09 martin-pi4 tvheadend[544]: epgdb: stored (size 4288124)
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: bits received from HRNG source: 1500064
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: bits sent to kernel pool: 1442048
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: entropy added to kernel pool: 1442048
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 successes: 75
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 failures: 0
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: HRNG source speed: (min=138.565; avg=448.267; max=494.550)Kibits/s
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: FIPS tests speed: (min=1.218; avg=12.178; max=18.847)Mibits/s
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: Lowest ready-buffers level: 2
Apr 16 19:21:27 martin-pi4 rngd[388]: stats: Entropy starvations: 0
Apr 16 20:21:27 martin-pi4 rngd[388]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 16 20:54:30 martin-pi4 tvheadend[544]: dvr: "New: Churchill Path to Victory" on "005 Channel 5" recorder starting
Apr 16 20:54:30 martin-pi4 tvheadend[544]: 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[544]: mpegts: 10964H in PCTV 461e (Sat) - tuning on PCTV 491e (DVB-S2) (Montage Technology M88DS3103) #2
Apr 16 20:54:30 martin-pi4 tvheadend[544]: 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[544]: linuxdvb: Unable to provide UNC value.
Apr 16 20:55:00 martin-pi4 tvheadend[544]: 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[544]: 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[544]: dvr: # type lang resolution aspect ratio sample rate channels
Apr 16 20:55:00 martin-pi4 tvheadend[544]: dvr: 1 MPEG2VIDEO ? ?
Apr 16 20:55:00 martin-pi4 tvheadend[544]: dvr: 2 MPEG2AUDIO nar ? ?
Apr 16 20:55:00 martin-pi4 tvheadend[544]: dvr: 3 MPEG2AUDIO eng ? ?
Apr 16 20:55:00 martin-pi4 tvheadend[544]: dvr: 4 DVBSUB eng
Apr 16 20:55:00 martin-pi4 tvheadend[544]: dvr: 5 TELETEXT
Apr 16 20:55:00 martin-pi4 tvheadend[544]: dvr: 6 TEXTSUB eng
Apr 16 20:55:40 martin-pi4 tvheadend[544]: epggrab: UK: Freesat - data completion timeout for 10964H in PCTV 461e (Sat)
Apr 16 20:55:40 martin-pi4 tvheadend[544]: epggrab: EIT: DVB Grabber - data completion timeout for 10964H in PCTV 461e (Sat)
Apr 16 21:17:01 martin-pi4 CRON[21711]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: snapshot start
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: queued to save (size 21644322)
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: brands 0
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: seasons 5781
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: episodes 18024
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: broadcasts 36806
Apr 16 21:21:08 martin-pi4 tvheadend[544]: epgdb: save start
Apr 16 21:21:09 martin-pi4 tvheadend[544]: epgdb: stored (size 4250351)
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: bits received from HRNG source: 1560064
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: bits sent to kernel pool: 1502048
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: entropy added to kernel pool: 1502048
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 successes: 78
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2 failures: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: HRNG source speed: (min=138.565; avg=449.706; max=494.550)Kibits/s
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: FIPS tests speed: (min=1.218; avg=12.305; max=18.847)Mibits/s
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: Lowest ready-buffers level: 2
Apr 16 21:21:27 martin-pi4 rngd[388]: stats: Entropy starvations: 0
Apr 16 21:21:27 martin-pi4 rngd[388]: 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[1028]: 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[544]: subscription: 00EE: "DVR: New: Churchill Path to Victory" unsubscribing from "005 Channel 5"
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: Executing "/home/pi/postproc"
Apr 16 22:10:00 martin-pi4 tvheadend[544]: dvr: "New: Churchill Path to Victory" on "005 Channel 5": End of program: Completed OK
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: Recording finished at Fri 16 Apr 2021 22:10:00
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: argc =#011#0118
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: argv[1] =#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[544]: spawn: argv[2] =#011New_ Churchill Path to Victory-20210416-2100-005 Channel 5.ts
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: argv[3] =#011New: Churchill Path to Victory
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: argv[4] =#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[544]: spawn: argv[5] =#011OK
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: argv[6] =#011Fri 16 Apr 2021 20:55:00 (1618602900)
Apr 16 22:10:00 martin-pi4 tvheadend[544]: spawn: argv[7] =#011Fri 16 Apr 2021 22:10:00 (1618607400)
Apr 16 22:10:13 martin-pi4 systemd[1]: Starting Daily apt download activities...
Apr 16 22:10:15 martin-pi4 systemd[1]: apt-daily.service: Succeeded.
Apr 16 22:10:15 martin-pi4 systemd[1]: Started Daily apt download activities.
