[mythtv-users] Help diagnosing why recordings sometimes failing?

Fri Mar 27 01:00:36 UTC 2015

Over the weekend I flashed DD-WRT onto my router.  Since then, I
occasionally have blocks of time where recordings fail.  0 byte recordings
result.  Then it starts recording again.  System has been extremely stable
for a long time, and I didn't change anything else in the setup.  The
record problems usually happen in the middle of the night.

Tuner is an HDHR-Prime connected to Verizon FIOS.  Here's the backend
version info, followed by an excerpt from the log from just before a failed
recording to right after it started.  The Daily Show is the recording that
failed.

Network has been fine, and I don't know for certain the recording glitches
are related to the router.  There are various suspicious looking things in
the log, but I'm not sure which might be causes and which side effects.

Any help is appreciated!

MythTV Version : v0.27-26-g77db957
MythTV Branch : fixes/0.27
Network Protocol : 77
Library API : 0.27.20131004-1
QT Version : 4.8.1
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_crystalhd using_dvb using_firewire using_frontend
using_hdhomerun using_ceton using_hdpvr using_ivtv using_joystick_menu
using_libcec using_libcrypto using_libdns_sd using_libfftw3 using_libxml2
using_lirc using_mheg using_opengl using_opengl_video using_qtwebkit
using_qtscript using_qtdbus using_sdl using_taglib using_v4l2 using_x11
using_xrandr using_xv using_profiletype using_bindings_perl
using_bindings_python using_bindings_php using_mythtranscode using_opengl
using_vaapi using_vdpau using_ffmpeg_threads using_mheg using_libass
using_libxml2

Mar 25 22:55:50 kenny mythbackend: mythbackend[26340]: I ProcessRequest
mainserver.cpp:1432 (HandleAnnounce) adding: kenny as a client (events: 1)
Mar 25 22:56:35 kenny mythbackend: mythbackend[26340]: N Expire
autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 7 min
Mar 25 22:59:30 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:1565 (HandlePendingRecordings) TVRec[1]: ASK_RECORDING 1 29 0 0
Mar 25 23:00:00 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:1048 (HandleStateChange) TVRec[1]: Changing from RecordingOnly
to None
Mar 25 23:00:00 kenny mythbackend: mythbackend[26340]: E HDHRStreamHandler
recorders/hdhrstreamhandler.cpp:206 (UpdateFilters) HDHRSH(13116DFE-0):
UpdateFilters called in wrong
tune mode
Mar 25 23:00:00 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:1048 (HandleStateChange) TVRec[5]: Changing from RecordingOnly
to None
Mar 25 23:00:00 kenny mythbackend: mythbackend[26340]: E HDHRStreamHandler
recorders/hdhrstreamhandler.cpp:206 (UpdateFilters) HDHRSH(13116DFE-2):
UpdateFilters called in wrong
tune mode
Mar 25 23:00:00 kenny mythbackend: mythbackend[26340]: I TVRecEvent
recordinginfo.cpp:1075 (FinishedRecording) Finished recording Nashville
"Nashville: On the Record 2": channel
 3506
Mar 25 23:00:00 kenny mythbackend: mythbackend[26340]: I CoreContext
scheduler.cpp:704 (UpdateRecStatus) Updating status for
Nashville:"Nashville: On the Record 2" on cardid 1 (
Recording => Recorded)
Mar 25 23:00:01 kenny mythbackend: mythbackend[26340]: I TVRecEvent
recordinginfo.cpp:1075 (FinishedRecording) Finished recording Broadchurch:
channel 3689
Mar 25 23:00:01 kenny mythbackend: mythbackend[26340]: I CoreContext
scheduler.cpp:704 (UpdateRecStatus) Updating status for Broadchurch on
cardid 5 (Recording => Recorded)
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:1048 (HandleStateChange) TVRec[1]: Changing from None to
RecordingOnly
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:3597 (TuningCheckForHWChange) TVRec[1]: HW Tuner: 1->1
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: E CoreContext
programinfo.cpp:2369 (GetPlaybackURL) ProgramInfo(3690_20150326030000.mpg):
GetPlaybackURL: '3690_2015032603
0000.mpg' should be local, but it can not be found.
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: N Scheduler
autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 14 min
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: W SystemEvent
mythsystemevent.cpp:55 (run) MythSystemEventHandler: Command
'/usr/share/mythtv/set_hdpvr_audio.sh 1' return
ed 127
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: I Scheduler
scheduler.cpp:2643 (HandleRecordingStatusChange) Tuning recording: "The
Daily Show With Jon Stewart":"Kirby Di
ck & Amy Ziering": channel 3690 on cardid 1, sourceid 3
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: I Scheduler
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for PLACE
PrepareToRecord
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: I Scheduler
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for CHECK -3
2180 0 UpdateRecStatus2 | Nashville |
Nashville: On the Record 2 | Members of the cast perform fan-favorite
songs; songwriters discuss their process and the music in the show; Deana
Carter performs with Charles Este
n; Reba performs. | EP015683360056
Mar 25 23:00:03 kenny mythbackend: mythbackend[26340]: I Scheduler
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for CHECK -3
2770 0 UpdateRecStatus2 | Broadchurch
|  | Miller struggles to control her sister amid family tensions; Alec has
to face his demons; Mark Latimer must make a decision. | EP017544300012
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: E HDHRStreamHandler
recorders/dtvsignalmonitor.cpp:322 (HandlePAT) DTVSigMon[1](13116DFE-0):
Program #0 not found in PAT!#
012Program Association Section#012 PSIP tableID(0x0) length(13)
extension(0x282)#012      version(1) current(1) section(0)
last_section(0)#012
     tsid(642) programCount(1)#012  program number   644 has PID 0x1928
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: E HDHRStreamHandler
recorders/dtvsignalmonitor.cpp:327 (HandlePAT) DTVSigMon[1](13116DFE-0):
But there is only one program in the PAT, so we'll just use it
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: N HDHRStreamHandler
recorders/dtvsignalmonitor.cpp:355 (HandlePMT) DTVSigMon[1](13116DFE-0):
PMT says program 644 is encrypted
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: E HDHRStreamHandler
recorders/hdhrstreamhandler.cpp:206 (UpdateFilters) HDHRSH(13116DFE-0):
UpdateFilters called in wrong tune mode
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:4117 (TuningNewRecorder) TVRec[1]: rec->GetPathname():
'/mnt/video/tv/3690_20150326030000.mpg'
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: E TVRecEvent
threadedfilewriter.cpp:122 (Open)
TFW(/mnt/video/tv/3690_20150326030000.mpg:-1): Opening file
'/mnt/video/tv/3690_20150326030000.mpg'.#012#011#011#011eno: Permission
denied (13)
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: E TVRecEvent
tv_rec.cpp:4123 (TuningNewRecorder) TVRec[1]: RingBuffer
'/mnt/video/tv/3690_20150326030000.mpg' not open...
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: I TVRecEvent
tv_rec.cpp:1048 (HandleStateChange) TVRec[1]: Changing from RecordingOnly
to None
Mar 25 23:00:05 kenny mythbackend: mythbackend[26340]: I CoreContext
scheduler.cpp:704 (UpdateRecStatus) Updating status for "The Daily Show
With Jon Stewart":"Kirby Dick & Amy Ziering" on cardid 1 (Tuning =>
Recording)
Mar 25 23:00:09 kenny mythbackend: mythbackend[26340]: I ProcessRequest
mainserver.cpp:1430 (HandleAnnounce) MainServer::ANN Monitor
Mar 25 23:00:09 kenny mythbackend: mythbackend[26340]: I ProcessRequest
mainserver.cpp:1432 (HandleAnnounce) adding: kenny as a client (events: 0)
Mar 25 23:00:09 kenny mythbackend: mythbackend[26340]: I ProcessRequest
mainserver.cpp:1430 (HandleAnnounce) MainServer::ANN Monitor
Mar 25 23:00:09 kenny mythbackend: mythbackend[26340]: I ProcessRequest
mainserver.cpp:1432 (HandleAnnounce) adding: kenny as a client (events: 0)
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20150327/a4f82e55/attachment.html>