[mythtv-users] Recordings without audio last night

Mon May 8 00:06:21 EDT 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)

Was this problem ever resolved? I got this exact same
problem twice this weekend. Two programs recorded
fine, the third recorded with no sound. A reboot
temporarily fixed it where the next 2 recordings
worked fine, but then again the 3rd recording lost
sound about 5 minutes into the show. 

Both times the audio was lost coincided with log
message like:

2006-05-07 19:54:58.371 NVP: Disabling Audio,
params(-1,-1,-1)
2006-05-07 19:54:58.375 NVP: Disabling Audio,
params(0,-1,-1)

I had been running myth 0.18 for about 5 months
without problems ... and more recently 0.19-fixes
which I built 3 weeks ago.

Not sure what info is relevant, but:
System: Debian Testing
Card: Hauppauge 350
Audio Codec Setting: MPEG-2 Hardware Encoder
IVTV Version: 0.4.0 (unchanged since myth first
installed)
Myth Version: 0.19-fixes, which I compiled myself
with: ./configure --enable-xvmc --enable-opengl-vsync