[mythtv-users] Missed Recordings on One DVB Tuner

James Crow crow.jamesm at gmail.com
Thu Jan 6 20:10:57 UTC 2011


I think I have found what is logged by the BE when a recording fails.
Before each recording that failed last night I see this log snippet:
2011-01-05 21:31:03.567 DVBSM(/dev/dvb/adapter1/frontend0), Warning:
Cannot measure Signal Strength
			eno: Invalid argument (22)
2011-01-05 21:31:03.609 DVBSM(/dev/dvb/adapter1/frontend0), Warning:
Cannot measure S/N
			eno: Invalid argument (22)

If I look at all occurrences of this error in the MBE logs I see that
some times the recording proceeds and other times it fails. Does
anyone know what causes this error?

Thanks,
James

Log outputs from a successful and a failed recording follow.
Here is 10 lines before and after "Better With You" which recorded
correctly (on input 1):
james at james-desktop:/var/log/mythtv$ grep -A 10 -B 10 "Better With
You" /var/log/mythtv/mythbackend.log
2011-01-05 20:27:34.502 Scheduled 1159 items in 3.5 = 0.02 match + 3.51 place
2011-01-05 20:29:29.746 TVRec(11): ASK_RECORDING 11 29 0 0
2011-01-05 20:29:30.462 TVRec(1): ASK_RECORDING 1 29 0 0
2011-01-05 20:29:30.569 TVRec(2): ASK_RECORDING 2 29 0 0
2011-01-05 20:30:02.625 TVRec(1): Changing from RecordingOnly to None
2011-01-05 20:30:02.646 Finished recording The Middle "Taking Back the
House": channel 1131
2011-01-05 20:30:03.026 Finished recording The Middle "Taking Back the
House": channel 1131
2011-01-05 20:30:03.701 TVRec(1): Changing from None to RecordingOnly
2011-01-05 20:30:03.838 TVRec(1): HW Tuner: 1->1
2011-01-05 20:30:04.377 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 14 min
2011-01-05 20:30:04.563 Started recording: "Better With You":"Better
With Skinny Jeans": channel 1131 on cardid 1, sourceid 1
2011-01-05 20:30:04.767 Updating status for "The Middle":"Taking Back
the House" on cardid 1 (Recording => Recorded)
2011-01-05 20:30:05.304 Updating status for "Better With You":"Better
With Skinny Jeans" on cardid 1 (Tuning => Recording)
2011-01-05 20:30:05.770 Reschedule requested for id 0.
2011-01-05 20:30:05.773 TVRec(1): rec->GetPathname():
'/myth1/recordings/1131_20110105203000.mpg'
2011-01-05 20:30:08.989 MainServer::ANN Monitor
2011-01-05 20:30:09.008 adding: james-desktop as a client (events: 0)
2011-01-05 20:30:09.017 MainServer::ANN Monitor
2011-01-05 20:30:09.024 adding: james-desktop as a client (events: 1)
2011-01-05 20:30:09.609 Scheduled 1158 items in 3.8 = 0.18 match + 3.63 place
2011-01-05 20:30:49.969 JobQueue: Commercial Detection Starting for
"Better With You":"Better With Skinny Jeans" recorded from channel
1131 at 2011-01-05T20:30:00
2011-01-05 20:30:50.186 Using runtime prefix = /usr
2011-01-05 20:30:50.207 Using configuration directory = /home/mythtv/.mythtv
2011-01-05 20:30:50.217 Empty LocalHostName.
2011-01-05 20:30:50.224 Using localhost value of james-desktop
2011-01-05 20:30:50.233 Testing network connectivity to '192.168.253.50'
2011-01-05 20:30:50.354 New DB connection, total: 1
2011-01-05 20:30:50.363 Connected to database 'mythconverg' at host:
192.168.253.50
2011-01-05 20:30:50.374 Closing DB connection named 'DBManager0'
2011-01-05 20:30:50.383 Connected to database 'mythconverg' at host:
192.168.253.50
2011-01-05 20:30:50.453 Current locale en_US
--
2011-01-05 20:57:59.960 TVRec(4): ASK_RECORDING 4 29 0 0
2011-01-05 20:58:00.033 TVRec(12): ASK_RECORDING 12 29 0 0
2011-01-05 20:58:32.029 TVRec(3): Changing from None to RecordingOnly
2011-01-05 20:58:32.057 TVRec(3): HW Tuner: 3->3
2011-01-05 20:58:32.527 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 7 min
2011-01-05 20:58:32.540 Started recording: "Modern Family":"Slow Down
Your Neighbors": channel 1131 on cardid 3, sourceid 1
2011-01-05 20:59:29.758 TVRec(2): ASK_RECORDING 2 29 0 0
2011-01-05 20:59:29.792 TVRec(1): ASK_RECORDING 1 29 0 0
2011-01-05 20:59:29.957 TVRec(11): ASK_RECORDING 11 29 0 0
2011-01-05 21:00:02.787 TVRec(1): Changing from RecordingOnly to None
2011-01-05 21:00:02.811 Finished recording Better With You "Better
With Skinny Jeans": channel 1131
2011-01-05 21:00:03.127 Finished recording Better With You "Better
With Skinny Jeans": channel 1131
2011-01-05 21:00:03.786 TVRec(1): Changing from None to RecordingOnly
2011-01-05 21:00:03.952 TVRec(1): HW Tuner: 1->1
2011-01-05 21:00:04.324 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 7 min
2011-01-05 21:00:04.451 Started recording: "Law & Order: Special
Victims Unit":"Pop; Possessed": channel 1041 on cardid 1, sourceid 1
2011-01-05 21:00:04.553 Updating status for "Better With You":"Better
With Skinny Jeans" on cardid 1 (Recording => Recorded)
2011-01-05 21:00:05.277 MainServer::ANN Monitor
2011-01-05 21:00:05.303 Updating status for "Law & Order: Special
Victims Unit":"Pop; Possessed" on cardid 1 (Tuning => Recording)
2011-01-05 21:00:05.307 adding: james-desktop as a client (events: 0)
2011-01-05 21:00:05.325 MainServer::ANN Monitor
2011-01-05 21:00:05.332 adding: james-desktop as a client (events: 1)
2011-01-05 21:00:05.553 Reschedule requested for id 0.
2011-01-05 21:00:05.620 TVRec(1): rec->GetPathname():
'/myth1/recordings/1041_20110105210000.mpg'
2011-01-05 21:00:08.970 Scheduled 1155 items in 3.4 = 0.02 match + 3.35 place
2011-01-05 21:00:17.421 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 7 min
2011-01-05 21:00:17.474 Expiring 368 MBytes for 3056 at Sun Jan 2
06:59:00 2011 => Handy Manny.  Too many episodes, we only want to keep
10.


Here is 10 lines around Cougar Town (on input 3) which failed to record:
james at james-desktop:/var/log/mythtv$ grep -A 10 -B 10 "Cougar Town"
/var/log/mythtv/mythbackend.log
2011-01-05 21:30:30.789 TVRec(3): ASK_RECORDING 3 29 0 0
2011-01-05 21:31:02.957 TVRec(3): Changing from RecordingOnly to None
2011-01-05 21:31:03.078 Finished recording Modern Family "Slow Down
Your Neighbors": channel 1131
2011-01-05 21:31:03.421 TVRec(3): Changing from None to RecordingOnly
2011-01-05 21:31:03.485 TVRec(3): HW Tuner: 3->3
2011-01-05 21:31:03.567 DVBSM(/dev/dvb/adapter1/frontend0), Warning:
Cannot measure Signal Strength
			eno: Invalid argument (22)
2011-01-05 21:31:03.609 DVBSM(/dev/dvb/adapter1/frontend0), Warning:
Cannot measure S/N
			eno: Invalid argument (22)
2011-01-05 21:31:03.745 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 7 min
2011-01-05 21:31:03.815 Started recording: "Cougar Town":"No Reason to
Cry": channel 1131 on cardid 3, sourceid 1
2011-01-05 21:31:03.910 Updating status for "Modern Family":"Slow Down
Your Neighbors" on cardid 3 (Tuning => Recorded)
2011-01-05 21:31:04.672 ProgramInfo(1131_20110105205900.mpg), Error:
GetPlaybackURL: '1131_20110105205900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.696 ProgramInfo(1211_20101114202900.mpg), Error:
GetPlaybackURL: '1211_20101114202900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.709 ProgramInfo(1071_20101222135900.mpg), Error:
GetPlaybackURL: '1071_20101222135900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.716 ProgramInfo(1071_20101223135900.mpg), Error:
GetPlaybackURL: '1071_20101223135900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.753 ProgramInfo(1621_20101014185900.mpg), Error:
GetPlaybackURL: '1621_20101014185900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.784 ProgramInfo(1211_20101114210000.mpg), Error:
GetPlaybackURL: '1211_20101114210000.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.798 ProgramInfo(1071_20101228135900.mpg), Error:
GetPlaybackURL: '1071_20101228135900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.810 ProgramInfo(1131_20101212205900.mpg), Error:
GetPlaybackURL: '1131_20101212205900.mpg' should be local, but it can
not be found.
2011-01-05 21:31:04.820 ProgramInfo(1071_20101224135900.mpg), Error:
GetPlaybackURL: '1071_20101224135900.mpg' should be local, but it can
not be found.
--
2011-01-05 21:59:01.183 adding: james-desktop as a client (events: 1)
2011-01-05 21:59:01.789 AFD: Opened codec 0x7fcd44007780,
id(MPEG2VIDEO) type(Video)
2011-01-05 21:59:01.807 AFD: codec AC3 has 2 channels
2011-01-05 21:59:01.817 AFD: Opened codec 0x7fcd44004f70, id(AC3) type(Audio)
2011-01-05 21:59:01.826 MythCoreContext: Connecting to backend server:
192.168.253.50:6543 (try 1 of 1)
2011-01-05 21:59:01.833 Using protocol version 63
2011-01-05 21:59:01.841 MainServer::ANN Playback
2011-01-05 21:59:01.849 adding: james-desktop as a client (events: 0)
2011-01-05 21:59:01.880 RecBase(2:/dev/dvb/adapter0/frontend0):
GetKeyframePositions(1538,9223372036854775807,#3) out of 38
2011-01-05 22:02:30.991 TVRec(3): Changing from RecordingOnly to None
2011-01-05 22:02:31.054 Updating status for "Cougar Town":"No Reason
to Cry" on cardid 3 (Tuning => Recorded)
2011-01-05 22:02:31.092 Finished recording Cougar Town "No Reason to
Cry": channel 1131
2011-01-05 22:02:31.105 Reschedule requested for id 0.
2011-01-05 22:02:34.473 Scheduled 1149 items in 3.3 = 0.02 match + 3.31 place
2011-01-05 22:03:20.308 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 7 min
2011-01-05 22:03:20.374 Expiring 368 MBytes for 3056 at Sun Jan 2
06:59:00 2011 => Handy Manny.  Too many episodes, we only want to keep
10.
2011-01-05 22:03:20.381 Expiring 695 MBytes for 3056 at Thu Dec 30
06:59:00 2010 => Handy Manny.  Too many episodes, we only want to keep
10.
2011-01-05 22:03:20.390 ERROR when trying to delete file:
myth://192.168.253.51:6543/3056_20110102065900.mpg. File doesn't
exist.  Database metadata will not be removed.
2011-01-05 22:03:20.398 Reschedule requested for id 0.
2011-01-05 22:03:20.408 ERROR when trying to delete file:
myth://192.168.253.51:6543/3056_20101230065900.mpg. File doesn't
exist.  Database metadata will not be removed.
2011-01-05 22:03:23.528 Scheduled 1149 items in 3.1 = 0.01 match + 3.10 place
2011-01-05 22:03:23.546 Reschedule requested for id 0.


More information about the mythtv-users mailing list