[mythtv-users] Recordings without audio last night

Tue Apr 18 07:37:15 UTC 2006

Last night Desperate Housewives recorded fine but all recordings after it
had no audio.  I noticed this today as I was leaving the house so my wife
power cycled the box.  When it came back up the recordings had audio again.

I am running .19 fixes brach about a week or two old.  PVR-350.  Working
fairly well for a few weeks now other than this.

Below is the part of the backend log for the time between the good and bad
recordings.  Any idea what went wrong?

The only thing that looks related are the NVP: Disabling Audio,
params(-1,-1,-1), NVP: Disabling Audio, params(0,-1,-1) lines.  In
recordings that work I see AFD: Opened codec 0x9b18bd0, id(MP2) type(Audio).
So if this is the problem what could cause this?

2006-04-16 21:00:03.103 TVRec(1): Changing from None to RecordingOnly
2006-04-16 21:00:04.164 ret_pid(0) child(18240) status(0x0)
2006-04-16 21:00:05.173 ret_pid(0) child(18240) status(0x0)
2006-04-16 21:00:06.181 ret_pid(0) child(18240) status(0x0)
2
2006-04-16 21:00:06.913 ret_pid(-1) child(18240) status(0x0)
2006-04-16 21:00:06.917 External Tuning program exited with no error
2006-04-16 21:00:07.075 Started recording: Desperate Housewives "Don't Look
at Me": channel 1002 on cardid 1, sourceid 1
2006-04-16 21:00:07.111 scheduler: Started recording: Desperate Housewives
"Don't Look at Me": channel 1002 on cardid 1, sourceid 1
2006-04-16 21:00:52.647 Commercial Flagging Starting for Desperate
Housewives "Don't Look at Me" recorded from channel 1002 at Sun Apr 16
21:00:00 2006
2006-04-16 21:00:52.673 commflag: Commercial Flagging Starting: Desperate
Housewives "Don't Look at Me" recorded from channel 1002 at Sun Apr 16
21:00:00 2006
2006-04-16 21:00:54.507 Using runtime prefix = /usr
2006-04-16 21:00:54.787 New DB connection, total: 1
2006-04-16 21:00:54.850 Connected to database 'mythconverg' at host:
localhost
2006-04-16 21:00:54.889 New DB connection, total: 2
2006-04-16 21:00:54.918 Connected to database 'mythconverg' at host:
localhost
2006-04-16 21:00:55.091 Connecting to backend server: 127.0.0.1:6543 (try 1
of 5)
2006-04-16 21:00:55.117 Using protocol version 26
2006-04-16 21:00:55.137 MainServer::HandleAnnounce Monitor
2006-04-16 21:00:55.141 adding: localhost.localdomain as a client (events:
0)
2006-04-16 21:00:55.249 MainServer::HandleAnnounce Monitor
2006-04-16 21:00:55.255 adding: localhost.localdomain as a client (events:
1)
0: start_time: 0.036 duration: 4.724
1: start_time: 0.026 duration: 4.661
stream: start_time: 0.289 duration: 52.597 bitrate=2882 kb/s
2006-04-16 21:01:01.427 AFD: Opened codec 0x9c05550, id(MPEG2VIDEO)
type(Video)
2006-04-16 21:01:01.488 AFD: Opened codec 0x9c05960, id(MP2) type(Audio)
2006-04-16 22:01:00.757 TVRec(1): Changing from RecordingOnly to None
2006-04-16 22:01:00.847 Finished recording Desperate Housewives "Don't Look
at Me": channel 1002
2006-04-16 22:01:00.848 Reschedule requested for id 0.
2006-04-16 22:01:01.041 scheduler: Finished recording: Desperate Housewives
"Don't Look at Me": channel 1002
2006-04-16 22:01:06.134 Finished recording Desperate Housewives "Don't Look
at Me": channel 1002
2006-04-16 22:01:07.225 Scheduled 1235 items in 6.4 = 0.03 match + 6.35
place
2006-04-16 22:01:07.266 scheduler: Last message repeated 1 times: Finished
recording: Desperate Housewives "Don't Look at Me": channel 1002
2006-04-16 22:01:07.289 scheduler: Scheduled items: Scheduled 1235 items in
6.4 = 0.03 match + 6.35 place
0: start_time: 0.036 duration: 328.723
1: start_time: 0.026 duration: 328.663
stream: start_time: 0.289 duration: 3652.593 bitrate=2862 kb/s
2006-04-16 22:01:07.295 AFD: Opened codec 0x9967ae0, id(MPEG2VIDEO)
type(Video)
2006-04-16 22:01:07.298 AFD: Opened codec 0x9b18bd0, id(MP2) type(Audio)
[mpeg2video @ 0x7f15ea4]Warning MVs not available
2006-04-16 22:02:17.171 commflag: Commercial Flagging Finished: Desperate
Housewives "Don't Look at Me" recorded from channel 1002 at Sun Apr 16
21:00:00 2006: 8 commercial break(s)
2006-04-16 22:02:17.188 Commercial Flagging Finished, 8 break(s) found.
0: start_time: 0.036 duration: 328.723
1: start_time: 0.026 duration: 328.663
stream: start_time: 0.289 duration: 3652.593 bitrate=2862 kb/s
2006-04-16 22:02:17.222 AFD: Opened codec 0x9b18bd0, id(MPEG2VIDEO)
type(Video)
2006-04-16 22:02:17.225 AFD: Opened codec 0x9af4420, id(MP2) type(Audio)
2006-04-16 22:02:55.439 Reschedule requested for id 0.
2006-04-16 22:02:57.601 Scheduled 1235 items in 2.2 = 0.04 match + 2.12
place
2006-04-16 22:02:57.610 scheduler: Scheduled items: Scheduled 1235 items in
2.2 = 0.04 match + 2.12 place
2006-04-16 22:26:17.258 Reschedule requested for id 0.
2006-04-16 22:26:21.938 Scheduled 1235 items in 4.7 = 0.06 match + 4.62
place
2006-04-16 22:26:22.031 scheduler: Scheduled items: Scheduled 1235 items in
4.7 = 0.06 match + 4.62 place
2006-04-16 22:28:05.790 Reschedule requested for id 75.
2006-04-16 22:28:09.177 Scheduled 1235 items in 3.4 = 1.39 match + 1.99
place
2006-04-16 22:28:09.188 scheduler: Scheduled items: Scheduled 1235 items in
3.4 = 1.39 match + 1.99 place
2006-04-16 23:23:10.521 Reschedule requested for id 0.
2006-04-16 23:23:15.986 Scheduled 1233 items in 5.5 = 0.06 match + 5.40
place
2006-04-16 23:23:16.053 scheduler: Scheduled items: Scheduled 1233 items in
5.5 = 0.06 match + 5.40 place
2006-04-16 23:30:03.072 TVRec(1): Changing from None to RecordingOnly
2006-04-16 23:30:04.116 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:05.122 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:06.131 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:07.141 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:08.152 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:09.159 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:10.167 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:11.175 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:12.183 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:13.195 ret_pid(0) child(18921) status(0x0)
2006-04-16 23:30:14.203 ret_pid(0) child(18921) status(0x0)
60
2006-04-16 23:30:14.249 ret_pid(-1) child(18921) status(0x0)
2006-04-16 23:30:14.252 External Tuning program exited with no error
2006-04-16 23:30:14.442 Started recording: South Park "Cartoon Wars":
channel 1060 on cardid 1, sourceid 1
2006-04-16 23:30:14.465 scheduler: Started recording: South Park "Cartoon
Wars": channel 1060 on cardid 1, sourceid 1
2006-04-16 23:30:59.525 Commercial Flagging Starting for South Park "Cartoon
Wars" recorded from channel 1060 at Sun Apr 16 23:30:00 2006
2006-04-16 23:30:59.540 commflag: Commercial Flagging Starting: South Park
"Cartoon Wars" recorded from channel 1060 at Sun Apr 16 23:30:00 2006
2006-04-16 23:31:01.376 Using runtime prefix = /usr
2006-04-16 23:31:01.716 New DB connection, total: 1
2006-04-16 23:31:01.788 Connected to database 'mythconverg' at host:
localhost
2006-04-16 23:31:01.835 New DB connection, total: 2
2006-04-16 23:31:01.853 Connected to database 'mythconverg' at host:
localhost
2006-04-16 23:31:01.946 Connecting to backend server: 127.0.0.1:6543 (try 1
of 5)
2006-04-16 23:31:01.979 Using protocol version 26
2006-04-16 23:31:01.997 MainServer::HandleAnnounce Monitor
2006-04-16 23:31:02.001 adding: localhost.localdomain as a client (events:
0)
0: start_time: 0.036 duration: 4.111
stream: start_time: 0.400 duration: 45.679 bitrate=2436 kb/s
2006-04-16 23:31:02.297 AFD: Opened codec 0x99fa450, id(MPEG2VIDEO)
type(Video)
2006-04-16 23:31:02.358 NVP: Disabling Audio, params(-1,-1,-1)
2006-04-16 23:31:02.377 NVP: Disabling Audio, params(0,-1,-1)
2006-04-16 23:31:02.516 MainServer::HandleAnnounce Monitor
2006-04-16 23:31:02.541 adding: localhost.localdomain as a client (events:
1)
2006-04-17 00:00:00.038 TVRec(1): Changing from RecordingOnly to None
2006-04-17 00:00:00.122 Finished recording South Park "Cartoon Wars":
channel 1060
2006-04-17 00:00:00.250 scheduler: Finished recording: South Park "Cartoon
Wars": channel 1060
2006-04-17 00:00:00.479 Finished recording South Park "Cartoon Wars":
channel 1060
0: start_time: 0.036 duration: 160.534
stream: start_time: 0.400 duration: 1783.715 bitrate=2467 kb/s
2006-04-17 00:00:00.619 AFD: Opened codec 0x9b18bd0, id(MPEG2VIDEO)
type(Video)
2006-04-17 00:00:00.689 NVP: Disabling Audio, params(-1,-1,-1)
2006-04-17 00:00:00.700 NVP: Disabling Audio, params(0,-1,-1)
2006-04-17 00:00:01.674 Reschedule requested for id 0.
2006-04-17 00:00:07.025 Scheduled 1229 items in 5.4 = 0.01 match + 5.35
place
2006-04-17 00:00:07.039 scheduler: Last message repeated 1 times: Finished
recording: South Park "Cartoon Wars": channel 1060
2006-04-17 00:00:07.049 scheduler: Scheduled items: Scheduled 1229 items in
5.4 = 0.01 match + 5.35 place
[mpeg2video @ 0x7f15ea4]invalid cbp at 12 10
[mpeg2video @ 0x7f15ea4]Warning MVs not available
2006-04-17 00:01:07.804 commflag: Commercial Flagging Finished: South Park
"Cartoon Wars" recorded from channel 1060 at Sun Apr 16 23:30:00 2006: 3
commercial break(s)
2006-04-17 00:01:07.823 Commercial Flagging Finished, 3 break(s) found.
0: start_time: 0.036 duration: 160.534
stream: start_time: 0.400 duration: 1783.715 bitrate=2467 kb/s
2006-04-17 00:01:07.870 AFD: Opened codec 0x9b18bd0, id(MPEG2VIDEO)
type(Video)
2006-04-17 00:01:07.874 NVP: Disabling Audio, params(-1,-1,-1)
2006-04-17 00:01:07.877 NVP: Disabling Audio, params(0,-1,-1)
2006-04-17 01:00:03.207 TVRec(1): Changing from None to RecordingOnly
2006-04-17 01:00:04.266 ret_pid(0) child(19303) status(0x0)
2006-04-17 01:00:05.274 ret_pid(0) child(19303) status(0x0)
2006-04-17 01:00:06.282 ret_pid(0) child(19303) status(0x0)
2006-04-17 01:00:07.290 ret_pid(0) child(19303) status(0x0)