[mythtv-users] Recordings get reset at 4:40am

[Stephen Worthington]

On Tue, 19 Apr 2016 13:39:28 -0400, you wrote:

>I record Rachel maddow every night, and occasionally it gets pushed to the
>4am showing.  Whenever it does that, my recording is only 19 minutes long.
>The first 40 minutes are lost. This has definitely happened the last 2
>times it recorded at 4am, which doesn't happen very often.
>
>It appears that /dev/video becomes unavailable. (It's an HDPVR)
>
>I've been looking at cron jobs that run at that time. The find on
>.mnt/disk/share doesn't affect the mythtv recordings library - and it runs
>every 2 minutes 24/7. I don't THINK the php5 session cleaner should have
>anything to do with recording. lastsnap just creates a symlink to a image
>file (the last snapshot taken by motion). speedtest runs every 10 minutes
>24/7 and runs a speed test and graphs it.  It affects network, not video or
>disk.
>
>That leaves checkmotion.sh, which makes sure motion hasn't crashed and
>restarts it if it does.  I could see that affecting /dev/video0, but I'd
>get an email if it did the restart, and no email was sent.  Also, it runs
>often
>
>I thought it might be mythbareupdate.py, but that seems to run 1 minute
>later than the problem I'm having, so...coincidence?
>
>Is there some job that mythtv is running that is closing the /dev/video0 ?
>Any ideas of other places to look?
>
>
>Apr 19 04:38:01 gossamer CRON[17528]: (root) CMD (find /mnt/disk/share/
>-type f -user mythtv -mmin +2 -exec chown plex:plex {} \; -exec chmod 664
>{} \; -exec touch {} \;)
>Apr 19 04:39:01 gossamer CRON[17565]: (root) CMD (  [ -x
>/usr/lib/php5/maxlifetime ] && [ -x /usr/lib/php5/sessionclean ] && [ -d
>/var/lib/php5 ] && /usr/lib/php5/sessionclean /var/lib/php5
>$(/usr/lib/php5/maxlifetime))
>Apr 19 04:39:01 gossamer CRON[17566]: (root) CMD (./checkmotion.sh | logger
>-t checkmotion)
>Apr 19 04:40:01 gossamer CRON[17622]: (root) CMD (/usr/local/sbin/
>lastsnap.pl | logger -t lastsnap)
>Apr 19 04:40:01 gossamer CRON[17623]: (root) CMD (find /mnt/disk/share/
>-type f -user mythtv -mmin +2 -exec chown plex:plex {} \; -exec chmod 664
>{} \; -exec touch {} \;)
>Apr 19 04:40:01 gossamer CRON[17626]: (jalcorn) CMD (~/bin/speedtest.sh >>
>speedtest.txt)
>Apr 19 04:41:02 gossamer CRON[17807]: (root) CMD
>(/usr/share/mythbuntu-bare/bareclient/mythbareupdate.py)
>
>
>
>mythbackend.log.1:Apr 19 04:40:05 gossamer mythbackend: mythbackend[18485]:
>I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding:
>gossamer(1e17020) as a client (events: 0)
>mythbackend.log.1:Apr 19 04:40:05 gossamer mythbackend: mythbackend[18485]:
>I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer:
>MainServer::ANN Monitor
>mythbackend.log.1:Apr 19 04:40:05 gossamer mythbackend: mythbackend[18485]:
>I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding:
>gossamer(1e10bc0) as a client (events: 0)
>mythbackend.log.1:Apr 19 04:40:06 gossamer mythbackend: mythbackend[18485]:
>E RecThread recorders/mpegrecorder.cpp:1418 (StartEncoding)
>MPEGRec[5](/dev/video0): StartEncoding: read failed, retry in 100
>msec:#012#011#011#011eno: Resource temporarily unavailable (11)
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(93) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1f88970) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(76) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1e17020) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(80) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1f0e4f0) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(60) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1f8a0a0) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(90) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1f31f30) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(84) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1df4f70) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(91) mainserver.cpp:7666 (connectionClosed) Control
>sock(1df46a0) disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I MythSocketThread(83) mainserver.cpp:7626 (connectionClosed) Monitor
>sock(1e10bc0) 'gossamer' disconnected
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I ProcessRequest mainserver.cpp:1698 (HandleAnnounce) MainServer:
>MainServer::ANN Monitor
>mythbackend.log.1:Apr 19 04:40:07 gossamer mythbackend: mythbackend[18485]:
>I ProcessRequest mainserver.cpp:1703 (HandleAnnounce) MainServer: adding:
>gossamer(1f88070) as a client (events: 0)

If your database is big enough, a database backup (mythtv-database) or
possibly optimize_db cron jobs can cause this.  I have to avoid
recording around 08:00 when my database backup happens, otherwise I
can lose the start of the recording.  But my database is huge,
compared to most (990 Mibytes compressed).