[mythtv-users] HD-PVR stops recording about 1-2 minutes into session

Mon Feb 8 23:49:18 UTC 2016

Getting this on mythbackend log:

2016-02-08 18:29:57.614399 I [1871/2101] TVRecEvent tv_rec.cpp:1050
(HandleStateChange) - TVRec[1]: Changing from None to RecordingOnly
2016-02-08 18:29:57.615375 I [1871/2101] TVRecEvent tv_rec.cpp:3603
(TuningCheckForHWChange) - TVRec[1]: HW Tuner: 1->1
2016-02-08 18:29:57.637130 I [1871/2101] TVRecEvent
recorders/v4lchannel.cpp:558 (SetInputAndFormat) -
V4LChannel[1](/dev/video0): SetInputAndFormat(8, NTSC) (v4l v2)
input_switch: 0 mode_switch: 0
2016-02-08 18:29:57.642701 N [1871/2181] Scheduler autoexpire.cpp:264
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 3.0 GB
w/freq: 15 min
2016-02-08 18:29:57.643070 I [1871/2181] Scheduler scheduler.cpp:2690
(HandleRecordingStatusChange) - Tuning recording: "CBS Evening News With
Scott Pelley": channel 3002 on cardid 1, sourceid 3
2016-02-08 18:29:57.645435 I [1871/2181] Scheduler scheduler.cpp:2140
(HandleReschedule) - Reschedule requested for PLACE PrepareToRecord
2016-02-08 18:29:57.727113 I [1871/2181] Scheduler scheduler.cpp:2253
(HandleReschedule) - Scheduled 495 items in 0.1 = 0.00 match + 0.00 check +
0.06 place
2016-02-08 18:29:58.696127 E [1871/2834] SignalMonitor
recorders/analogsignalmonitor.cpp:85 (VerifyHDPVRaudio) -
AnalogSigMon[1](/dev/video0): Audio desired 4, current 3 min 0 max 4
2016-02-08 18:29:58.707807 E [1871/2834] SignalMonitor
recorders/analogsignalmonitor.cpp:95 (VerifyHDPVRaudio) -
AnalogSigMon[1](/dev/video0): Changed audio encoding from 3 to 4.
2016-02-08 18:30:00.790750 I [1871/1871] CoreContext scheduler.cpp:705
(UpdateRecStatus) - Updating status for "CBS Evening News With Scott
Pelley" on cardid 1 (Tuning => Recording)
2016-02-08 18:30:00.850814 I [1871/2101] TVRecEvent tv_rec.cpp:4130
(TuningNewRecorder) - TVRec[1]: rec->GetPathname():
'/storage/recordings/3002_20160208233000.mpg'
2016-02-08 18:30:23.453776 I [1871/2881] Metadata_11802 jobqueue.cpp:2156
(DoMetadataLookupThread) - JobQueue: Metadata Lookup Starting for "CBS
Evening News With Scott Pelley" recorded from channel 3002 at
2016-02-08T23:30:00Z
2016-02-08 18:30:24.998939 I [1871/2699] ProcessRequest mainserver.cpp:1446
(HandleAnnounce) - MainServer::ANN Playback
2016-02-08 18:30:24.998952 I [1871/2699] ProcessRequest mainserver.cpp:1448
(HandleAnnounce) - adding: localhost.localdomain as a client (events: 0)
2016-02-08 18:30:24.999851 I [1871/2699] ProcessRequest mainserver.cpp:1446
(HandleAnnounce) - MainServer::ANN Monitor
2016-02-08 18:30:24.999860 I [1871/2699] ProcessRequest mainserver.cpp:1448
(HandleAnnounce) - adding: localhost.localdomain as a client (events: 1)
2016-02-08 18:30:28.460057 I [1871/2924] Commflag_11803 jobqueue.cpp:2281
(DoFlagCommercialsThread) - JobQueue: Commercial Detection Starting for
"CBS Evening News With Scott Pelley" recorded from channel 3002 at
2016-02-08T23:30:00Z
2016-02-08 18:30:28.921473 I [1871/2699] ProcessRequest mainserver.cpp:1446
(HandleAnnounce) - MainServer::ANN Playback
2016-02-08 18:30:28.921480 I [1871/2699] ProcessRequest mainserver.cpp:1448
(HandleAnnounce) - adding: localhost.localdomain as a client (events: 0)
2016-02-08 18:30:28.922214 I [1871/2699] ProcessRequest mainserver.cpp:1446
(HandleAnnounce) - MainServer::ANN Monitor
2016-02-08 18:30:28.922222 I [1871/2699] ProcessRequest mainserver.cpp:1448
(HandleAnnounce) - adding: localhost.localdomain as a client (events: 1)
2016-02-08 18:30:31.351728 I [1871/2699] ProcessRequest mainserver.cpp:1446
(HandleAnnounce) - MainServer::ANN Playback
2016-02-08 18:30:31.351735 I [1871/2699] ProcessRequest mainserver.cpp:1448
(HandleAnnounce) - adding: localhost.localdomain as a client (events: 0)
2016-02-08 18:30:33.924702 E [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:485 (Poll) - DevRdB(/dev/video0): poll eof
(POLLHUP)
2016-02-08 18:30:33.924716 W [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:555 (Poll) - DevRdB(/dev/video0): Poll took
an unusually long time 2502 ms
2016-02-08 18:30:33.924770 E [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:604 (CheckForErrors) - DevRdB(/dev/video0):
Problem reading fd(68)
                        eno: No such device (19)
2016-02-08 18:30:33.925401 E [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:485 (Poll) - DevRdB(/dev/video0): poll eof
(POLLHUP)
2016-02-08 18:30:33.925425 E [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:604 (CheckForErrors) - DevRdB(/dev/video0):
Problem reading fd(68)
                        eno: No such device (19)
2016-02-08 18:30:33.926053 E [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:485 (Poll) - DevRdB(/dev/video0): poll eof
(POLLHUP)
2016-02-08 18:30:33.926076 E [1871/2879] DeviceReadBuffer
recorders/DeviceReadBuffer.cpp:604 (CheckForErrors) - DevRdB(/dev/video0):
Problem reading fd(68)
                        eno: No such device (19)
2

Also, dmesg is showing

[  300.014053] hdpvr 2-6:1.0: device video0 disconnected
[  304.528125] hdpvr 2-6:1.0: firmware version 0x1e dated Mar  7 2012
08:25:15
[  304.904517] hdpvr 2-6:1.0: device now attached to video0
[  321.709914] hdpvr 2-6:1.0: device video0 disconnected
[  326.223559] hdpvr 2-6:1.0: firmware version 0x1e dated Mar  7 2012
08:25:15
[  326.598877] hdpvr 2-6:1.0: device now attached to video0
[  408.513407] snd_hda_intel 0000:02:00.1: IRQ timing workaround is
activated for card #1. Suggest a bigger bdl_pos_adj.
[root@

That IRQ timing, if I recall, has been occurring for a while.

My HD-PVR is probably 5+ years old now, though I moved from an Intel E6300
to an Intel i7 last year.

I recently was plugging and unplugging  a USB Blu-Ray drive.

I am on Fedora 22 mythtv-0.27.4-6.fc22.x86_64, kernel Linux
localhost.localdomain 4.1.6-201.fc22.x86_64 #1 SMP Fri Sep 4 17:49:24 UTC
2015 x86_64 x86_64 x86_64 GNU/Linux
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.mythtv.org/pipermail/mythtv-users/attachments/20160208/d16b41b0/attachment.html>