[mythtv-users] hd-pvr problem recording audio after firmware update

John P Poet jppoet at gmail.com
Wed Jul 21 23:09:09 UTC 2010


On Wed, Jul 21, 2010 at 4:49 PM, JWA <jwa at macbidouille.com> wrote:
> On Wed, Jul 21, 2010 at 15:30, John P Poet <jppoet at gmail.com> wrote:
>> On Wed, Jul 21, 2010 at 4:22 PM, JWA <jwa at macbidouille.com> wrote:
>>> Hello All,
>>>
>>> I was having problems with my hd-pvr's recordings (it would have
>>> breaks in the recordings causing it to miss 30 seconds or so every so
>>> often).  So I decided to update the firmware to see if that would fix
>>> the problem.  The good news is that I no longer have that problem.
>>> That bad news is that now the audio will randomly not work when
>>> recording (it worked without a problem before).  Usually it will
>>> record fine for a couple shows, and then when it stops working it
>>> won't work for any more recordings until I restart mythbackend.  I
>>> tested recording on my Mac (with EyeTV) and had no problem with the
>>> audio, so I know it's not a hardware problem.
>>>
>>> The one odd thing I noticed about the recordings is that the ones that
>>> don't work say they are aac.  I have SPDIF chosen as the input and all
>>> the recording profiles say AC3 for the audio format.  I have tried all
>>> of the recording profiles and the problem happens with all of them.  I
>>> even tried creating a new recording profile, just to test, but it
>>> doesn't show up in the front end as an option (which I guess it
>>> another problem but not that important).
>>>
>>> I tried updating to the latest version of Ubuntu (10.04) and MythTV
>>> (0.23) to see if that would fix it, but I still get the same problem.
>>>
>>> I don't know if it's needed, but here's the dmesg output:
>>>
>>> [59281.690062] usb 1-5: new high speed USB device using ehci_hcd and address 7
>>> [59281.868016] usb 1-5: configuration #1 chosen from 1 choice
>>> [59282.067504] hdpvr 1-5:1.0: untested firmware version 0x15, the
>>> driver might not work
>>> [59282.380416] hdpvr 1-5:1.0: device now attached to /dev/video0
>>>
>>> and here's the modinfo:
>>>
>>> filename:
>>> /lib/modules/2.6.32-24-generic/kernel/drivers/media/video/hdpvr/hdpvr.ko
>>> description:    Hauppauge HD PVR driver
>>> author:         Janne Grunau
>>> license:        GPL
>>> srcversion:     9E493050A99F9F194A6B10B
>>> alias:          usb:v2040p4902d*dc*dsc*dp*ic*isc*ip*
>>> alias:          usb:v2040p4901d*dc*dsc*dp*ic*isc*ip*
>>> alias:          usb:v2040p4900d*dc*dsc*dp*ic*isc*ip*
>>> depends:        videodev,v4l2-common
>>> vermagic:       2.6.32-24-generic SMP mod_unload modversions
>>> parm:           video_nr:video device number (-1=Auto) (array of int)
>>> parm:           hdpvr_debug:enable debugging output (int)
>>> parm:           default_video_input:default video input: 0=Component /
>>> 1=S-Video / 2=Composite (uint)
>>> parm:           default_audio_input:default audio input: 0=RCA back /
>>> 1=RCA front / 2=S/PDIF (uint)
>>> parm:           boost_audio:boost the audio signal (bool)
>>>
>>>
>>> Thanks for any help.
>>
>>
>> Please post the mythbackend log with "-v record".
>>
>>
>> John
>> --
>> A: Because it messes up the order in which people normally read text.
>> Q: Why is top-posting such a bad thing?
>
> Hi John,
>
> Thanks for the response.  Here is the output from a recording I just
> scheduled, and it is trying to record aac so it is one of the bad
> ones:
>
> 2010-07-21 15:36:33.069 New DB scheduler connection
> 2010-07-21 15:36:33.071 Connected to database 'mythconverg' at host: localhost
> 2010-07-21 15:36:33.097 Enabling Upnpmedia rebuild thread.
> 2010-07-21 15:36:34.302 Main::Registering HttpStatus Extension
> 2010-07-21 15:36:34.304 Enabled verbose msgs:  important general record
> 2010-07-21 15:36:34.308 AutoExpire: CalcParams(): Max required Free
> Space: 1.0 GB w/freq: 15 min
> 2010-07-21 15:36:36.079 Reschedule requested for id -1.
> 2010-07-21 15:36:36.190 Scheduled 12 items in 0.1 = 0.02 match + 0.08 place
> 2010-07-21 15:36:36.196 Seem to be woken up by USER
> 2010-07-21 15:36:43.100 UPnpMedia: BuildMediaMap - no VideoStartupDir
> set,  skipping scan.
> 2010-07-21 15:37:40.809 MainServer::ANN Monitor
> 2010-07-21 15:37:53.079 AutoExpire: CalcParams(): Max required Free
> Space: 1.0 GB w/freq: 15 min
> 2010-07-21 15:37:56.652 MainServer::ANN Monitor
> 2010-07-21 15:38:32.347 Reschedule requested for id 333.
> 2010-07-21 15:38:32.395 Scheduled 13 items in 0.0 = 0.00 match + 0.04 place
> 2010-07-21 15:38:32.400 TVRec(2): RecordPending on inputid 1
> 2010-07-21 15:38:32.402 TVRec(2): StartRecording(The Andy Griffith Show)
> 2010-07-21 15:38:32.404 TVRec(2): ASK_RECORDING 2 0 0 0
> 2010-07-21 15:38:32.431 TVRec(2): StartedRecording(0xa7c3a0)
> fn(/var/cache/mythtv/1075_20100721153900.mpg)
> 2010-07-21 15:38:32.433 TVRec(2): ClearFlags(CancelNextRecording,) ->
> RunMainLoop,
> 2010-07-21 15:38:32.435 TVRec(2): Changing from None to RecordingOnly
> 2010-07-21 15:38:32.437 TVRec(2):
> ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
> 2010-07-21 15:38:32.439 TVRec(2): Request: Program(yes) channel()
> input() flags(Recording,)
> 2010-07-21 15:38:32.466 ProgramInfo(): Updated pathname '':'' ->
> '1075_20100721153900.mpg'
> 2010-07-21 15:38:32.484 TVRec(2): HW Tuner: 2->2
> 2010-07-21 15:38:32.488 TVRec(2): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,
> 2010-07-21 15:38:32.490 TVRec(2): No recorder yet, calling TuningFrequency
> 2010-07-21 15:38:32.572 ret_pid(5681) child(5681) status(0x0)
> 2010-07-21 15:38:32.573 External Tuning program exited with no error
> 2010-07-21 15:38:32.653 TVRec(2): ClearFlags(NeedToStartRecorder,) ->
> RunMainLoop,
> 2010-07-21 15:38:32.655 TVRec(2): SetFlags(NeedToStartRecorder,) ->
> RunMainLoop,NeedToStartRecorder,
> 2010-07-21 15:38:32.657 TVRec(2): Starting Recorder
> 2010-07-21 15:38:32.682 Using profile 'Default' to record
> 2010-07-21 15:38:32.683 TVRec(2): rec->GetFileName():
> '/var/cache/mythtv/1075_20100721153900.mpg'
> 2010-07-21 15:38:32.691 RecBase(2:/dev/video0):
> SetRingBuffer(0x9ed9c0) '/var/cache/mythtv/1075_20100721153900.mpg'
> 2010-07-21 15:38:32.693 RecBase(2:/dev/video0): SetRecording(0xa7c3a0)
> title(The Andy Griffith Show)
> 2010-07-21 15:38:32.772 MPEGRec(/dev/video0): usingv4l2(1)
> has_v4l2_vbi(0) has_buggy_vbi(1)
> 2010-07-21 15:38:32.799 DevRdB(/dev/video0): buffer size 9400 KB
> 2010-07-21 15:38:32.801 MPEGRec(/dev/video0): DRB ready
> 2010-07-21 15:38:32.803 MPEGRec(/dev/video0): SetStreamData(0x9ff3b0) -- begin
> 2010-07-21 15:38:32.805 MPEGRec(/dev/video0): SetStreamData(0x9ff3b0) -- end 1
> 2010-07-21 15:38:32.806 MPEGRec(/dev/video0): HandleSingleProgramPAT(NULL)
> 2010-07-21 15:38:32.809 MPEGRec(/dev/video0): Initial startup of recorder
> 2010-07-21 15:38:32.811 MPEGRec(/dev/video0): Checking Resolution
> 2010-07-21 15:38:32.812 TVRec(2):
> SetFlags(RecorderRunning,RingBufferReady,) ->
> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady,
> 2010-07-21 15:38:32.814 TVRec(2): ClearFlags(NeedToStartRecorder,) ->
> RunMainLoop,RecorderRunning,RingBufferReady,
> 2010-07-21 15:38:32.829 AutoExpire: CalcParams(): Max required Free
> Space: 3.0 GB w/freq: 15 min
> 2010-07-21 15:38:32.831 Started recording: The Andy Griffith Show:
> channel 1075 on cardid 2, sourceid 1
> 2010-07-21 15:38:32.836 MainServer::ANN Monitor
> 2010-07-21 15:38:41.262 MPEGRec(/dev/video0): Got Resolution 1280x720
> 2010-07-21 15:38:41.263 MPEGRec(/dev/video0): Old bitrate 13500/20200 VBR
> 2010-07-21 15:38:41.265 MPEGRec(/dev/video0): New bitrate 9000/11000 kbps VBR
> 2010-07-21 15:38:41.275 MPEGRec(/dev/video0): StartEncoding
> 2010-07-21 15:38:41.519 MPEGRec(/dev/video0): Encoding started
> 2010-07-21 15:38:41.562 CreatePATSingleProgram()
> 2010-07-21 15:38:41.564 PAT in input stream
> 2010-07-21 15:38:41.565 Program Association Table
>  PSIP tableID(0x0) length(17) extension(0x0)
>      version(1) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 2
>  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:38:41.568 desired_program(1) pid(0x100)
> 2010-07-21 15:38:41.570 pmt_pid(0x100)
> 2010-07-21 15:38:41.571 PAT for output stream
> 2010-07-21 15:38:41.573 Program Association Table
>  PSIP tableID(0x0) length(13) extension(0x0)
>      version(1) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 1
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:38:41.576 CreatePMTSingleProgram()
> 2010-07-21 15:38:41.578 PMT in input stream
> 2010-07-21 15:38:41.579 Program Map Table ver(0) pid(0x100) pnum(1) len(45)
>  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
>  Unknown(136) Descriptor (0x88) length(4)
>
>  Stream #0 pid(0x1011) type(video-h264  0x1b)
>  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
>  Stream #1 pid(0x1100) type(audio-aac  0xf)
>
> 2010-07-21 15:38:41.581 PMT for output stream
> 2010-07-21 15:38:41.584 Program Map Table ver(0) pid(0x100) pnum(1) len(45)
>  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
>  Unknown(136) Descriptor (0x88) length(4)
>
>  Stream #0 pid(0x1011) type(video-h264  0x1b)
>  Registration Descriptor: 'HDMV' Blu-Ray A/V for read-only media (H.264 TS)
>  Stream #1 pid(0x1100) type(audio-aac  0xf)
>
> 2010-07-21 15:38:41.590 PID 0x1011 Found Payload Start
> 2010-07-21 15:38:41.594 PID 0x1100 Found Payload Start
> 2010-07-21 15:44:06.237 CreatePATSingleProgram()
> 2010-07-21 15:44:06.239 PAT in input stream
> 2010-07-21 15:44:06.240 Program Association Table
>  PSIP tableID(0x0) length(17) extension(0x0)
>      version(2) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 2
>  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:44:06.242 desired_program(1) pid(0x100)
> 2010-07-21 15:44:06.243 pmt_pid(0x100)
> 2010-07-21 15:44:06.245 PAT for output stream
> 2010-07-21 15:44:06.247 Program Association Table
>  PSIP tableID(0x0) length(13) extension(0x0)
>      version(2) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 1
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:44:38.944 MainServer::ANN Monitor
> 2010-07-21 15:44:42.505 MainServer::ANN Monitor
> 2010-07-21 15:44:42.522 MainServer::ANN Monitor
> 2010-07-21 15:44:42.868 ProgramInfo(): Updated pathname '':'' ->
> '1075_20100721153900.mpg'
> 2010-07-21 15:44:42.920 mythbackend version:
> branches/release-0-23-fixes [24158] www.mythtv.org
> 2010-07-21 15:44:42.921 Using runtime prefix = /usr
> 2010-07-21 15:44:42.923 Using configuration directory = /home/mythtv/.mythtv
> 2010-07-21 15:44:42.925 Empty LocalHostName.
> 2010-07-21 15:44:42.937 New DB connection, total: 1
> 2010-07-21 15:44:42.942 Connected to database 'mythconverg' at host: localhost
> 2010-07-21 15:44:42.944 Closing DB connection named 'DBManager0'
> 2010-07-21 15:44:42.947 Connected to database 'mythconverg' at host: localhost
> 2010-07-21 15:44:42.952 Current MythTV Schema Version (DBSchemaVer): 1254
> 2010-07-21 15:44:42.955 ProgramInfo(): Updated pathname '':'' ->
> '1075_20100721153900.mpg'
> 2010-07-21 15:44:43.232 AFD: Opened codec 0x1e30d80, id(H264) type(Video)
> 2010-07-21 15:44:43.233 AFD: codec AAC has 2 channels
> 2010-07-21 15:44:43.235 AFD: Opened codec 0x1e2fad0, id(AAC) type(Audio)
> 2010-07-21 15:44:43.357 [h264 @ 0x7fc9f8c3a360]B picture before any
> references, skipping
> 2010-07-21 15:44:43.358 [h264 @ 0x7fc9f8c3a360]decode_slice_header error
> 2010-07-21 15:44:43.361 [h264 @ 0x7fc9f8c3a360]no frame!
> 2010-07-21 15:44:43.364 AFD Error: Unknown decoding error
> 2010-07-21 15:44:43.366 [h264 @ 0x7fc9f8c3a360]B picture before any
> references, skipping
> 2010-07-21 15:44:43.367 [h264 @ 0x7fc9f8c3a360]decode_slice_header error
> 2010-07-21 15:44:43.370 [h264 @ 0x7fc9f8c3a360]no frame!
> 2010-07-21 15:44:43.372 AFD Error: Unknown decoding error
> 2010-07-21 15:44:43.373 [h264 @ 0x7fc9f8c3a360]B picture before any
> references, skipping
> 2010-07-21 15:44:43.375 [h264 @ 0x7fc9f8c3a360]decode_slice_header error
> 2010-07-21 15:44:43.377 [h264 @ 0x7fc9f8c3a360]no frame!
> 2010-07-21 15:44:43.379 AFD Error: Unknown decoding error
> 2010-07-21 15:44:43.510 Preview: Grabbed preview
> '/var/cache/mythtv/1075_20100721153900.mpg' 1280x720 at 150s
> 2010-07-21 15:44:43.590 ~MythContext waiting for threads to exit.
> 2010-07-21 15:44:51.854 MainServer::ANN Monitor
> 2010-07-21 15:45:48.786 CreatePATSingleProgram()
> 2010-07-21 15:45:48.788 PAT in input stream
> 2010-07-21 15:45:48.790 Program Association Table
>  PSIP tableID(0x0) length(17) extension(0x0)
>      version(3) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 2
>  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:45:48.792 desired_program(1) pid(0x100)
> 2010-07-21 15:45:48.794 pmt_pid(0x100)
> 2010-07-21 15:45:48.795 PAT for output stream
> 2010-07-21 15:45:48.813 Program Association Table
>  PSIP tableID(0x0) length(13) extension(0x0)
>      version(3) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 1
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:46:39.670 CreatePATSingleProgram()
> 2010-07-21 15:46:39.672 PAT in input stream
> 2010-07-21 15:46:39.673 Program Association Table
>  PSIP tableID(0x0) length(17) extension(0x0)
>      version(4) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 2
>  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:46:39.675 desired_program(1) pid(0x100)
> 2010-07-21 15:46:39.676 pmt_pid(0x100)
> 2010-07-21 15:46:39.678 PAT for output stream
> 2010-07-21 15:46:39.680 Program Association Table
>  PSIP tableID(0x0) length(13) extension(0x0)
>      version(4) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 1
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:46:54.551 CreatePATSingleProgram()
> 2010-07-21 15:46:54.760 PAT in input stream
> 2010-07-21 15:46:54.775 Program Association Table
>  PSIP tableID(0x0) length(17) extension(0x0)
>      version(5) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 2
>  program number     0 has PID 0x  1f   data  0x 0 0x 0 0xe0 0x1f
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0
>
> 2010-07-21 15:46:54.779 desired_program(1) pid(0x100)
> 2010-07-21 15:46:54.783 pmt_pid(0x100)
> 2010-07-21 15:46:54.785 PAT for output stream
> 2010-07-21 15:46:54.786 Program Association Table
>  PSIP tableID(0x0) length(13) extension(0x0)
>      version(5) current(1) section(0) last_section(0)
>         tsid: 0
>  programCount: 1
>  program number     1 has PID 0x 100   data  0x 0 0x 1 0xe1 0x 0


Odd. My best guess based on this info, is that the HD-PVR is trying to
record the audio from the RCA jacks instead of the S/PDIF.  I wonder
if the new firmware requires some sort of protocol change in setting
up the capture inputs.

Does your STB drive both the S/PDIF and analog audio outputs at the
same time?  If so, could you try hooking up both the S/PDIF and the
RCA audio jacks at the same time to at least see if you get any audio?
 If you do get audio, that would indicate that it is recording from
the wrong audio input on the HD-PVR.

Are you running 0.23-fixes?


John
-- 
A: Because it messes up the order in which people normally read text.
Q: Why is top-posting such a bad thing?


More information about the mythtv-users mailing list