Some recordings not starting on time

I have noticed that a number of shows I have recorded are missing a few seconds.

The logs seem to back this up. Here's a snippet:
2021/09/22 22:00:15.673235 [DVR] Starting job 1632373200-11 Big Brother on ch=[5.1]
It shows that the recording started at 22:00:15 and not at 22:00:00.

There were two recordings that finished up at 22:00:00. It looks like there was some amount of housekeeping for those two before starting the Big Brother recording.

Although there are 4 tuners, it appears the jobs (recordings) are managed sequentially. At least that's what the logs seem to indicate. I have other examples as well, but thought I'd ask about this one example. Maybe this is functioning as designed. I'm trying this out in the hopes of replacing my TiVo. I am running Channels DVR v1.0.5 on my DiskStation (DS1520+, Intel Celeron J4125, DSM6).

I'm including some additional logs from the 22:00 hour for completeness.

2021/09/22 22:00:00.027486 [TNR] Closed connection to 10A0E96B/2 for ch5.1 KPIX-TV
2021/09/22 22:00:00.027665 [TNR] Closed connection to 10A0E96B/3 for ch2.1 KTVU-HD
2021/09/22 22:00:00.873214 [DVR] Marking expired job 1632366000-44 Survivor
2021/09/22 22:00:04.211554 [SNR] Statistics for "TV/Alter Ego/Alter Ego S01E01 The Auditions Begin 2021-09-22-2100.mpg": ss=77%,72%-84% snq=99%,96%-100% seq=99%,0%-100% bps=10582612,0-14378240 pps=904,0-1231
2021/09/22 22:00:04.230613 [DVR] Finished job 1632369600-46 Alter Ego
2021/09/22 22:00:06.702847 [SNR] Statistics for "TV/Survivor/Survivor S41E01 A New Era 2021-09-22-2000.mpg": ss=88%,81%-99% snq=100% seq=100% bps=13441932,1559648-16252224 pps=1149,0-2989
2021/09/22 22:00:06.709776 [DVR] Finished job 1632366000-44 Survivor
2021/09/22 22:00:12.353194 [DVR] Marking expired job 1632369600-46 Alter Ego
2021/09/22 22:00:15.673029 [ERR] Could not find job 1632369600-46 to update
2021/09/22 22:00:15.673235 [DVR] Starting job 1632373200-11 Big Brother on ch=[5.1]
2021/09/22 22:00:16.180859 [TNR] Opened connection to 10A0E96B/2 for ch5.1 KPIX-TV
2021/09/22 22:00:16.181013 [DVR] Starting job 1632373200-48 A Million Little Things on ch=[7.1]
2021/09/22 22:00:16.181049 [DVR] Waiting 8h59m43.818956805s until next job 1632405600-10 Today
2021/09/22 22:00:16.503919 [TNR] Opened connection to 10A0E96B/3 for ch7.1 KGO-HD
2021/09/22 22:00:16.573077 [DVR] Recording for job 1632373200-11 from 10A0E96B ch5.1 into "TV/Big Brother/Big Brother S23E33 2021-09-22-2200.mpg" for 59m44.326658406s
2021/09/22 22:00:16.573154 [DVR] Recording for job 1632373200-48 from 10A0E96B ch7.1 into "TV/A Million Little Things/A Million Little Things S04E01 Family First 2021-09-22-2200.mpg" for 59m43.818906641s
2021/09/22 22:00:18.104523 [DVR] Processing file-489: TV/Alter Ego/Alter Ego S01E01 The Auditions Begin 2021-09-22-2100.mpg
2021/09/22 22:00:18.112436 [DVR] Waiting 8h59m41.88757172s until next job 1632405600-10 Today
2021/09/22 22:00:22.719705 [DVR] Waiting 8h59m37.28030083s until next job 1632405600-10 Today
2021/09/22 22:00:36.648995 [WRN] Buffer for 10A0E96B ch5.1 is more than 50% full (clients=1, len=33555368)
2021/09/22 22:00:40.012202 [DVR] Refreshing metadata for A Million Little Things (15522919)
2021/09/22 22:00:47.151030 [WRN] Buffer for 10A0E96B ch5.1 is more than 75% full (clients=1, len=50332228)
2021/09/22 22:00:47.244723 [WRN] Buffer for 10A0E96B ch7.1 is more than 50% full (clients=1, len=33555368)
2021/09/22 22:00:47.303711 [DVR] Processing file-486: TV/Survivor/Survivor S41E01 A New Era 2021-09-22-2000.mpg
2021/09/22 22:00:47.304145 [DVR] Running commercial detection on file 489 (TV/Alter Ego/Alter Ego S01E01 The Auditions Begin 2021-09-22-2100.mpg)
2021/09/22 22:00:55.272567 [WRN] Buffer for 10A0E96B ch5.1 is more than 95% full (clients=1, len=63754008)
2021/09/22 22:00:55.761584 [IDX] Generating video index for job 1632373200-11
2021/09/22 22:00:56.960097 [WRN] Buffer for 10A0E96B ch5.1 is more than 99% full (clients=1, len=66438948)
2021/09/22 22:01:03.186884 [WRN] Buffer for 10A0E96B ch7.1 is more than 75% full (clients=1, len=50332488)
2021/09/22 22:01:06.153490 [IDX] Generating video index for job 1632373200-48
2021/09/22 22:06:35.550584 [DVR] Commercial detection for Alter Ego S01E01 The Auditions Begin 2021-09-22-2100.mpg finished with 14 markers in 5m48.246612019s.
2021/09/22 22:06:38.375403 [DVR] Running commercial detection on file 486 (TV/Survivor/Survivor S41E01 A New Era 2021-09-22-2000.mpg)
2021/09/22 22:21:09.027133 [DVR] Commercial detection for Survivor S41E01 A New Era 2021-09-22-2000.mpg finished with 18 markers in 14m30.688068352s.

Any help would be appreciated.

Thank you.

You can add padding to your passes

Thank you for your reply.

I am still trying to understand if this intended behavior. Am I correct that these recordings are being managed in a serial/sequential manner as seen in the logs? Is there anything I can do to allow them to be processed in parallel? Meaning if I were to have 4 shows all starting at the same time, they would all occur at the 00mins/00secs interval and not one after another.

Yes it's the intended behavior. If you had more recordings scheduled than tuners available, starting them in parallel would cause four random ones to succeed instead of the first four in priority order.

1 Like