On Sun, 2009-10-25 at 10:38 -0400, Jacob Michael van Wessem wrote: > Hello, > > I'm having some problems with the Hauppage WinTV-HVR-1600 that I > recently bought. I'm running Jaunty on a system with an ASUS A7V333 > motherboard with BIOS version 1017, AMD Athlon XP 1800+ and nVideo > GeForce 7600 GT video card with 256mb ram. The WinTV-HVR-1600 is the > kind with the MCE remote. Current kernel is labeled 2.6.28-16-generic > #55-Ubuntu SMP and I installed the > cx-18 module as per http://www.mythtv.org/wiki/Hauppauge_HVR-1600 > > I'm having two problems: > > - Analog video has no sound. > - Changing the channel on the analog tuner stops playback and seems to > lock up all use of the WinTV-HVR-1600 > > I hope you guys can help me resolve these problems. > > Here is a typical session: > > System startup: > > [ 14.927186] cx18: Start initialization, version 1.2.0 > [ 14.927973] cx18-0: Initializing card 0 > [ 14.927983] cx18-0: Autodetected Hauppauge card > [ 14.937864] cx18 0000:00:0e.0: PCI INT A -> GSI 17 (level, low) -> > IRQ 17 > [ 14.937975] cx18-0: Unreasonably low latency timer, setting to 64 > (was 32) > [ 14.943431] cx18-0: cx23418 revision 01010000 (B) > [ 15.234170] tveeprom 1-0050: Hauppauge model 74541, rev C6B6, > serial# 6314962 > [ 15.234179] tveeprom 1-0050: MAC address is 00-0D-FE-60-5B-D2 > [ 15.234184] tveeprom 1-0050: tuner model is Philips FM1236 MK5 (idx > 116, type 43) > [ 15.234190] tveeprom 1-0050: TV standards NTSC(M) (eeprom 0x08) > [ 15.234195] tveeprom 1-0050: audio processor is CX23418 (idx 38) > [ 15.234199] tveeprom 1-0050: decoder processor is CX23418 (idx 31) > [ 15.234203] tveeprom 1-0050: has radio > [ 15.234207] cx18-0: Autodetected Hauppauge HVR-1600 > [ 15.234213] cx18-0: Simultaneous Digital and Analog TV capture > supported > [ 15.421386] tuner 2-0043: chip found @ 0x86 (cx18 i2c driver #0-1) > [ 15.440345] tda9887 2-0043: creating new instance > [ 15.440354] tda9887 2-0043: tda988[5/6/7] found > [ 15.446498] tuner 2-0061: chip found @ 0xc2 (cx18 i2c driver #0-1) > [ 15.467427] cs5345 1-004c: chip found @ 0x98 (cx18 i2c driver #0-0) > [ 15.491851] tuner-simple 2-0061: creating new instance > [ 15.491862] tuner-simple 2-0061: type set to 43 (Philips NTSC MK3 > (FM1236MK3 or FM1236/F)) > [ 15.494124] cx18-0: Registered device video0 for encoder MPEG (64 x > 32 kB) > [ 15.494132] DVB: registering new adapter (cx18) > [ 15.612500] MXL5005S: Attached at address 0x63 > [ 15.612516] DVB: registering adapter 0 frontend 0 (Samsung S5H1409 > QAM/8VSB Frontend)... > [ 15.612934] cx18-0: DVB Frontend registered > [ 15.612941] cx18-0: Registered DVB adapter0 for TS (32 x 32 kB) > [ 15.613067] cx18-0: Registered device video32 for encoder YUV (16 x > 128 kB) > [ 15.613238] cx18-0: Registered device vbi0 for encoder VBI (20 x > 51984 bytes) > [ 15.613341] cx18-0: Registered device video24 for encoder PCM audio > (256 x 4 kB) > [ 15.613438] cx18-0: Registered device radio0 for encoder radio > [ 15.613444] cx18-0: Initialized card: Hauppauge HVR-1600 > [ 15.613539] cx18: End initialization > [ 15.678354] C-Media PCI 0000:00:05.0: PCI INT A -> GSI 17 (level, > low) -> IRQ 17 > [ 22.928450] ivtv: Start initialization, version 1.4.1 > [ 22.928559] ivtv: End initialization > [ 22.945289] lirc_pvr150: disagrees about version of symbol > ivtv_reset_ir_gpio > [ 22.945302] lirc_pvr150: Unknown symbol ivtv_reset_ir_gpio > [ 30.604990] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-cpu.fw > [ 30.771584] cx18-0: loaded v4l-cx23418-cpu.fw firmware (158332 > bytes) > [ 30.795006] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-apu.fw > [ 30.964545] cx18-0: loaded v4l-cx23418-apu.fw firmware V00120000 > (141200 bytes) > [ 30.973159] cx18-0: FW version: 0.0.74.0 (Release 2007/03/12) > [ 31.300412] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-cpu.fw > [ 31.462333] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-apu.fw > [ 31.801713] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-dig.fw > [ 31.991741] cx18-0 843: loaded v4l-cx23418-dig.fw firmware (16382 > bytes) > [ 32.009660] cx18-0 843: verified load of v4l-cx23418-dig.fw > firmware (16382 bytes) > > It's not clear to me why driver reports that firmware is requesting > apu and cpu again after reporting that they are loaded.
That's normal. It's loaded twice to get around a firmware bug with digital TV. > I think the C-Media PCI line is for the onboard sound, not the > HVR-1600, but I've left it in here because it's sharing interrupt 17 > with cx-18. cat /proc/interrupts: > > 17: 57174 IO-APIC-fasteoi ehci_hcd:usb1, cx18-0, CMI8738-MC6 > > Is it possible that this interrupt sharing causes the lockups and lack > of sound? Your problem doewsn't look like it is related to the CX23418's interrupt being held off too long. So maybe, but not likely. > Alright. Start MythTV, go to watch TV. Analog playback starts, but > there is no audio. Nothing in kernel log. > > Now change the channel in MythTV. Playback stops indefinitely. Kernel > log shows this: > [ 1057.324029] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1057.348043] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1057.372025] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.332033] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_CAPTURE_STOP; clearing busy and trying to proceed > [ 1062.364024] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_RELEASE_MDL; clearing busy and trying to proceed > [ 1062.396025] cx18-0: mbox was found stuck busy when setting up for > CX18_DESTROY_TASK; clearing busy and trying to proceed > [ 1062.428038] cx18-0: mbox was found stuck busy when setting up for > CX18_CREATE_TASK; clearing busy and trying to proceed > [ 1062.467782] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_CHANNEL_TYPE; clearing busy and trying to proceed > [ 1062.496033] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_VER_CROP_LINE; clearing busy and trying to proceed > [ 1062.528036] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_MISC_PARAMETERS; clearing busy and trying to proceed > [ 1062.560034] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_MISC_PARAMETERS; clearing busy and trying to proceed > [ 1062.592040] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_MISC_PARAMETERS; clearing busy and trying to proceed > [ 1062.624030] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_MISC_PARAMETERS; clearing busy and trying to proceed > [ 1062.656039] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_CAPTURE_LINE_NO; clearing busy and trying to proceed > [ 1062.688175] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_RAW_VBI_PARAM; clearing busy and trying to proceed > [ 1062.720046] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_INDEXTABLE; clearing busy and trying to proceed > [ 1062.752045] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_SET_VIDEO_IN; clearing busy and trying to proceed > [ 1062.784029] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL_ACK; clearing busy and trying to proceed > [ 1062.816027] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.840041] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.864032] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.888051] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.912035] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.936031] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1062.960040] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed This is a bad condition. I've never seen it happen before; I just put the log message in there in case it did one day. Repeated "stuck busy" messages indicate the CX23418 has gone "stupid" or is not responding properly. > OK. Let's rmmod cx18 and reload it with debug 15 (this will get more > verbose): > > [ 1193.934791] cx18: Start initialization, version 1.2.0 > [ 1193.934907] cx18-0: Initializing card 0 > [ 1193.934913] cx18-0: Autodetected Hauppauge card > [ 1193.943736] cx18-0: info: base addr: 0xc8000000 > [ 1193.943742] cx18-0: info: Enabling pci device > [ 1193.943764] cx18 0000:00:0e.0: PCI INT A -> GSI 17 (level, low) -> > IRQ 17 > [ 1193.943779] cx18-0: info: cx23418 (rev 0) at 00:0e.0, irq: 17, > latency: 64, memory: 0xc8000000 > [ 1193.943783] cx18-0: info: attempting ioremap at 0xc8000000 len > 0x04000000 > [ 1193.947747] cx18-0: cx23418 revision 01010000 (B) > [ 1194.038592] cx18-0: info: GPIO initial dir: 0000cffe/0000ffff out: > 00003001/00000000 > [ 1194.038617] cx18-0: info: activating i2c... > [ 1194.212312] tveeprom 1-0050: Hauppauge model 74541, rev C6B6, > serial# 6314962 > [ 1194.212319] tveeprom 1-0050: MAC address is 00-0D-FE-60-5B-D2 > [ 1194.212323] tveeprom 1-0050: tuner model is Philips FM1236 MK5 (idx > 116, type 43) > [ 1194.212327] tveeprom 1-0050: TV standards NTSC(M) (eeprom 0x08) > [ 1194.212331] tveeprom 1-0050: audio processor is CX23418 (idx 38) > [ 1194.212334] tveeprom 1-0050: decoder processor is CX23418 (idx 31) > [ 1194.212337] tveeprom 1-0050: has radio > [ 1194.212341] cx18-0: Autodetected Hauppauge HVR-1600 > [ 1194.212344] cx18-0: info: NTSC tuner detected > [ 1194.212347] cx18-0: Simultaneous Digital and Analog TV capture > supported > [ 1194.351350] tuner 2-0043: chip found @ 0x86 (cx18 i2c driver #0-1) > [ 1194.351495] tda9887 2-0043: creating new instance > [ 1194.351498] tda9887 2-0043: tda988[5/6/7] found > [ 1194.357625] tuner 2-0061: chip found @ 0xc2 (cx18 i2c driver #0-1) > [ 1194.362940] cs5345 1-004c: chip found @ 0x98 (cx18 i2c driver #0-0) > [ 1194.365280] tuner-simple 2-0061: creating new instance > [ 1194.365288] tuner-simple 2-0061: type set to 43 (Philips NTSC MK3 > (FM1236MK3 or FM1236/F)) > [ 1194.366682] cx18-0: info: Allocate encoder MPEG stream: 64 x 32768 > buffers (2048kB total) > [ 1194.366767] cx18-0: info: Allocate TS stream: 32 x 32768 buffers > (1024kB total) > [ 1194.366804] cx18-0: info: Allocate encoder YUV stream: 16 x 131072 > buffers (2048kB total) > [ 1194.366839] cx18-0: info: Allocate encoder VBI stream: 20 x 51984 > buffers (1015kB total) > [ 1194.366870] cx18-0: info: Allocate encoder PCM audio stream: 256 x > 4096 buffers (1024kB total) > [ 1194.367043] cx18-0: info: Allocate encoder IDX stream: 32 x 32768 > buffers (1024kB total) > [ 1194.367362] cx18-0: Registered device video0 for encoder MPEG (64 x > 32 kB) > [ 1194.367368] DVB: registering new adapter (cx18) > [ 1194.525014] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-cpu.fw > [ 1194.548213] MXL5005S: Attached at address 0x63 > [ 1194.548227] DVB: registering adapter 0 frontend 0 (Samsung S5H1409 > QAM/8VSB Frontend)... > [ 1194.567088] cx18-0: DVB Frontend registered > [ 1194.567095] cx18-0: Registered DVB adapter0 for TS (32 x 32 kB) > [ 1194.567139] cx18-0: Registered device video32 for encoder YUV (16 x > 128 kB) > [ 1194.567172] cx18-0: Registered device vbi0 for encoder VBI (20 x > 51984 bytes) > [ 1194.567203] cx18-0: Registered device video24 for encoder PCM audio > (256 x 4 kB) > [ 1194.567234] cx18-0: Registered device radio0 for encoder radio > [ 1194.567238] cx18-0: Initialized card: Hauppauge HVR-1600 > [ 1194.567277] cx18: End initialization > [ 1194.665016] cx18-0: loaded v4l-cx23418-cpu.fw firmware (158332 > bytes) > [ 1194.687326] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-apu.fw > [ 1195.204144] cx18-0: info: load segment a00000-a07fff > [ 1195.223682] cx18-0: info: load segment ae0000-ae00ff > [ 1195.223839] cx18-0: info: load segment b00000-b1a65f > [ 1195.288238] cx18-0: loaded v4l-cx23418-apu.fw firmware V00120000 > (141200 bytes) > [ 1195.294223] cx18-0: info: 1 MiniMe Encoder Firmware 0.0.74.0 > (Release 2007/03/12) > [ 1195.294231] cx18-0: FW version: 0.0.74.0 (Release 2007/03/12) > [ 1195.684061] cx18-0: api: CX18_CPU_DEBUG_PEEK32 cmd 0x20000003 > args 0x00000000 > [ 1195.684164] cx18-0: api: CX18_APU_START cmd 0x10000001 args > 0x000000b9 0x00000000 > [ 1195.685376] cx18-0: api: CX18_APU_RESETAI cmd 0x10000005 args > [ 1195.704063] cx18-0: warning: sending CX18_APU_RESETAI timed out > waiting 20 msecs for RPU acknowledgement > [ 1195.704074] cx18-0: api: CX18_APU_STOP cmd 0x10000002 args > 0x00000000 > [ 1195.716054] cx18-0: mbox was found stuck busy when setting up for > CX18_APU_STOP; clearing busy and trying to proceed > [ 1195.736058] cx18-0: warning: sending CX18_APU_STOP timed out > waiting 20 msecs for RPU acknowledgement This shouldn't be happening. Something is wrong with your PCI bus, or your power, or your APU or CPU firmware image. Or you have a bad CX23418. The APU (audio processing unit) should respond and pick up its mail almost immediately, and not leave the APU's incoming mailbox busy (full). > [ 1195.740130] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-cpu.fw > [ 1195.906342] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-apu.fw > [ 1196.034837] cx18-0: info: load segment a00000-a07fff > [ 1196.054586] cx18-0: info: load segment ae0000-ae00ff > [ 1196.054744] cx18-0: info: load segment b00000-b1a65f > [ 1196.125354] cx18-0: info: 1 MiniMe Encoder Firmware 0.0.74.0 > (Release 2007/03/12) > [ 1196.416058] cx18-0: api: CX18_CPU_DEBUG_PEEK32 cmd 0x20000003 > args 0x00000000 > [ 1196.416198] cx18-0: api: CX18_APU_START cmd 0x10000001 args > 0x000000b9 0x00000000 > [ 1196.417406] cx18-0: api: CX18_APU_RESETAI cmd 0x10000005 args > [ 1196.417841] cx18-0: api: CX18_APU_STOP cmd 0x10000002 args > 0x00000000 > [ 1196.417925] cx18 0000:00:0e.0: firmware: requesting > v4l-cx23418-dig.fw > [ 1196.629074] cx18-0 843: loaded v4l-cx23418-dig.fw firmware (16382 > bytes) > [ 1196.646410] cx18-0 843: verified load of v4l-cx23418-dig.fw > firmware (16382 bytes) > [ 1196.646511] cx18-0: info: Changing input from 1 to 0 > [ 1196.646514] cx18-0: info: Mute > [ 1196.646518] cx18-0 843: info: decoder set video input 7, audio > input 8 > [ 1196.874385] cx18-0 843: info: decoder set video input 7, audio > input 8 > [ 1196.874466] cx18-0: info: Unmute > [ 1196.874471] cx18-0: info: Switching standard to 1000. > [ 1196.874475] cx18-0 843: info: changing video std to fmt 1 > [ 1196.874491] cx18-0 843: info: PLL regs = int: 15, frac: 2876158, > post: 4 > [ 1196.874495] cx18-0 843: info: Video PLL = 107.999999 MHz > [ 1196.874498] cx18-0 843: info: Pixel rate = 13.499999 Mpixel/sec > [ 1196.874501] cx18-0 843: info: ADC XTAL/pixel clock decimation > ratio = 2.121 > [ 1196.874505] cx18-0 843: info: Chroma sub-carrier initial freq = > 3.579545 MHz > [ 1196.874510] cx18-0 843: info: hblank 122, hactive 720, vblank 26, > vactive 481, vblank656 38, src_dec 543, burst 0x5a, luma_lpf 1, uv_lpf > 1, comb 0x66, sc 0x087c00 > [ 1196.876084] cx18-0: info: Mute > [ 1196.876088] cx18-0: info: v4l2 ioctl: set frequency 1076 > [ 1196.880431] cx18-0: info: Unmute > [ 1196.883681] cx18-0: info: Mute > [ 1196.885911] cx18-0 843: info: decoder set video input 7, audio > input 0 > [ 1196.885976] cx18-0: info: Unmute > [ 1196.907271] cx18-0: info: Mute > [ 1196.907281] cx18-0 843: info: changing video std to fmt 1 > [ 1196.907298] cx18-0 843: info: PLL regs = int: 15, frac: 2876158, > post: 4 > [ 1196.907301] cx18-0 843: info: Video PLL = 107.999999 MHz > [ 1196.907304] cx18-0 843: info: Pixel rate = 13.499999 Mpixel/sec > [ 1196.907307] cx18-0 843: info: ADC XTAL/pixel clock decimation > ratio = 2.121 > [ 1196.907311] cx18-0 843: info: Chroma sub-carrier initial freq = > 3.579545 MHz > [ 1196.907316] cx18-0 843: info: hblank 122, hactive 720, vblank 26, > vactive 481, vblank656 38, src_dec 543, burst 0x5a, luma_lpf 1, uv_lpf > 1, comb 0x66, sc 0x087c00 > [ 1196.910267] cx18-0 843: info: decoder set video input 7, audio > input 8 > [ 1196.910337] cx18-0: info: Unmute > [ 1196.932515] cx18-0: info: Input unchanged > [ 1196.932528] cx18-0: info: Switching standard to b000. > [ 1196.932533] cx18-0 843: info: changing video std to fmt 1 > [ 1196.932550] cx18-0 843: info: PLL regs = int: 15, frac: 2876158, > post: 4 > [ 1196.932554] cx18-0 843: info: Video PLL = 107.999999 MHz > [ 1196.932557] cx18-0 843: info: Pixel rate = 13.499999 Mpixel/sec > [ 1196.932560] cx18-0 843: info: ADC XTAL/pixel clock decimation > ratio = 2.121 > [ 1196.932563] cx18-0 843: info: Chroma sub-carrier initial freq = > 3.579545 MHz > [ 1196.932568] cx18-0 843: info: hblank 122, hactive 720, vblank 26, > vactive 481, vblank656 38, src_dec 543, burst 0x5a, luma_lpf 1, uv_lpf > 1, comb 0x66, sc 0x087c00 > [ 1196.935640] cx18-0: info: Input unchanged > [ 1196.939236] cx18-0: info: Mute > [ 1196.939245] cx18-0: info: v4l2 ioctl: set frequency 2996 > [ 1196.941995] cx18-0: info: Unmute > > Now try watching tv in MythTV again. Works (no sound) > > [ 1287.648309] cx18-0: info: Start feed: pid = 0x0 index = 0 > [ 1287.648320] cx18-0: info: Starting Transport DMA > [ 1287.648324] cx18-0: info: Start encoder stream TS > [ 1287.648331] cx18-0: api: CX18_CREATE_TASK cmd 0x40000001 args > 0x20020000 > [ 1287.648568] cx18-0: api: CX18_CPU_SET_CHANNEL_TYPE cmd 0x20020001 > args 0x00000000 0x00000007 > [ 1287.648755] cx18-0: api: CX18_CPU_DE_SET_MDL_ACK cmd 0x20040002 > args 0x00000000 0x00dc0c50 0x00dc0c58 > [ 1287.656157] cx18-0: api: CX18_CPU_CAPTURE_START cmd 0x20020002 > args 0x00000000 > [ 1287.956156] cx18-0: info: Stop feed: pid = 0x0 index = 0 > [ 1287.956164] cx18-0: info: Stopping Transport DMA > [ 1287.956167] cx18-0: info: Stop Capture > [ 1287.956172] cx18-0: api: CX18_CPU_CAPTURE_STOP cmd 0x20020003 > args 0x00000000 > [ 1288.256042] cx18-0: api: CX18_CPU_DE_RELEASE_MDL cmd 0x20040006 > args 0x00000000 > [ 1288.556032] cx18-0: api: CX18_DESTROY_TASK cmd 0x40000002 args > 0x00000000 > [ 1288.565268] cx18-0: info: Start feed: pid = 0x0 index = 0 > [ 1288.565280] cx18-0: info: Starting Transport DMA > [ 1288.565284] cx18-0: info: Start encoder stream TS > [ 1288.565290] cx18-0: api: CX18_CREATE_TASK cmd 0x40000001 args > 0x20020000 > [ 1288.565359] cx18-0: api: CX18_CPU_SET_CHANNEL_TYPE cmd 0x20020001 > args 0x00000000 0x00000007 > [ 1288.565411] cx18-0: api: CX18_CPU_DE_SET_MDL_ACK cmd 0x20040002 > args 0x00000000 0x00dc0c50 0x00dc0c58 > [ 1288.567396] cx18-0: api: CX18_CPU_CAPTURE_START cmd 0x20020002 > args 0x00000000 > [ 1288.864322] cx18-0: info: Start feed: pid = 0x1ffb index = 1 > [ 1288.947032] cx18-0: info: Start feed: pid = 0x30 index = 2 > [ 1288.986940] cx18-0: info: Start feed: pid = 0x31 index = 3 > [ 1288.987026] cx18-0: info: Start feed: pid = 0x34 index = 4 > [ 1289.027432] cx18-0: info: Start feed: pid = 0x1388 index = 5 > [ 1289.027517] cx18-0: info: Start feed: pid = 0x1389 index = 6 > [ 1289.027573] cx18-0: info: Start feed: pid = 0x138a index = 7 > [ 1289.027627] cx18-0: info: Start feed: pid = 0x138b index = 8 > [ 1289.027680] cx18-0: info: Start feed: pid = 0x1408 index = 9 > [ 1289.027733] cx18-0: info: Start feed: pid = 0x1409 index = 10 > [ 1289.027786] cx18-0: info: Start feed: pid = 0x140a index = 11 > [ 1289.027840] cx18-0: info: Start feed: pid = 0x140b index = 12 > [ 1343.893201] cx18-0: warning: failed to be awakened upon RPU > acknowledgment sending CX18_CPU_DE_SET_MDL; timed out waiting 28 msecs > [ 1393.845930] cx18-0: warning: failed to be awakened upon RPU > acknowledgment sending CX18_CPU_DE_SET_MDL; timed out waiting 28 msecs > [ 1437.591889] cx18-0: info: Input unchanged > [ 1437.595203] cx18-0: info: Mute > [ 1437.595211] cx18-0: info: v4l2 ioctl: set frequency 2996 > [ 1437.597791] cx18-0: info: Unmute > [ 1438.785345] cx18-0 843: info: decoder set size 480x480 -> scale > 524288x0 > [ 1438.785773] cx18-0: api: CX18_CPU_SET_AUDIO_PARAMETERS cmd > 0x20020011 args 0xffffffff 0x000000ba > [ 1438.785915] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.785974] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.786028] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.786178] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.786231] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.786284] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.786379] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.786433] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.786486] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.786574] cx18-0: api: CX18_CPU_SET_AUDIO_PARAMETERS cmd > 0x20020011 args 0xffffffff 0x000000ea > [ 1438.786632] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.786686] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.786738] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.786827] cx18-0: api: CX18_CPU_SET_VIDEO_RATE cmd 0x20020005 > args 0xffffffff 0x00000000 0x005b8d80 0x00003a98 0x00000000 > [ 1438.786892] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.786954] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.787021] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.787507] cx18-0: api: CX18_CPU_SET_VIDEO_RATE cmd 0x20020005 > args 0xffffffff 0x00000000 0x0044aa20 0x00003a98 0x00000000 > [ 1438.787714] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.787773] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.787829] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.787922] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000001 0x00000000 0x00000000 > [ 1438.787978] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000000 0x00000000 0x00000000 > [ 1438.793941] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0xffffffff 0x00000002 0x00000000 0x00000000 > [ 1438.794952] cx18-0: info: Start encoder stream encoder MPEG > [ 1438.794959] cx18-0: api: CX18_CREATE_TASK cmd 0x40000001 args > 0x20020000 > [ 1438.795078] cx18-0: api: CX18_CPU_SET_CHANNEL_TYPE cmd 0x20020001 > args 0x00000001 0x00000001 > [ 1438.795206] cx18-0: api: CX18_CPU_SET_VER_CROP_LINE cmd 0x2002001b > args 0x00000001 0x00000000 > [ 1438.795261] cx18-0: api: CX18_CPU_SET_MISC_PARAMETERS cmd > 0x20020015 args 0x00000001 0x00000003 0x00000001 > [ 1438.795317] cx18-0: api: CX18_CPU_SET_MISC_PARAMETERS cmd > 0x20020015 args 0x00000001 0x00000008 0x00000000 > [ 1438.795413] cx18-0: api: CX18_CPU_SET_MISC_PARAMETERS cmd > 0x20020015 args 0x00000001 0x00000004 0x00000001 > [ 1438.795544] cx18-0: api: CX18_CPU_SET_MISC_PARAMETERS cmd > 0x20020015 args 0x00000001 0x0000000c > [ 1438.795596] cx18-0: api: CX18_CPU_SET_CAPTURE_LINE_NO cmd > 0x20020017 args 0x00000001 0x00000138 0x00000139 > [ 1438.795680] cx18-0 843: info: PLL regs = int: 15, frac: 2876158, > post: 4 > [ 1438.795684] cx18-0 843: info: Video PLL = 107.999999 MHz > [ 1438.795688] cx18-0 843: info: Pixel rate = 13.499999 Mpixel/sec > [ 1438.795691] cx18-0 843: info: ADC XTAL/pixel clock decimation > ratio = 2.121 > [ 1438.795694] cx18-0 843: info: Chroma sub-carrier initial freq = > 3.579545 MHz > [ 1438.795700] cx18-0 843: info: hblank 122, hactive 720, vblank 26, > vactive 481, vblank656 38, src_dec 543, burst 0x5a, luma_lpf 1, uv_lpf > 1, comb 0x66, sc 0x087c00 > [ 1438.795747] cx18-0: info: Setup VBI h: 1 lines c000c bpl 1444 fr 1 > 20602060 307090d0 > [ 1438.795753] cx18-0: api: CX18_CPU_SET_RAW_VBI_PARAM cmd 0x20020016 > args 0x00000001 0x000c000c 0x000005a4 0x00000001 0x20602060 0x307090d0 > [ 1439.092027] cx18-0: api: CX18_CPU_SET_INDEXTABLE cmd 0x20020010 > args 0x00000000 > [ 1439.092089] cx18-0: api: CX18_CPU_SET_VIDEO_IN cmd 0x20020004 > args 0x00000001 0x00000000 0x00000000 0x00000000 0x00000000 0x00000000 > [ 1439.092163] cx18-0: api: CX18_CPU_SET_VIDEO_RESOLUTION cmd > 0x20020006 args 0x00000001 0x000001e0 0x000001e0 > [ 1439.092735] cx18-0: api: CX18_CPU_SET_STREAM_OUTPUT_TYPE cmd > 0x20020012 args 0x00000001 0x00000000 > [ 1439.092796] cx18-0: api: CX18_CPU_SET_ASPECT_RATIO cmd 0x2002001e > args 0x00000001 0x00000002 > [ 1439.092850] cx18-0: api: CX18_CPU_SET_GOP_STRUCTURE cmd 0x2002001c > args 0x00000001 0x0000000f 0x00000003 > [ 1439.092901] cx18-0: api: CX18_CPU_SET_AUDIO_PARAMETERS cmd > 0x20020011 args 0x00000001 0x000000ea > [ 1439.092964] cx18-0: api: CX18_CPU_SET_AUDIO_MUTE cmd 0x20020014 > args 0x00000001 0x00000000 > [ 1439.093028] cx18-0: api: CX18_CPU_SET_VIDEO_RATE cmd 0x20020005 > args 0x00000001 0x00000000 0x0044aa20 0x00003a98 0x00000000 > [ 1439.093087] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0x00000001 0x00000001 0x00000000 0x00000000 > [ 1439.093142] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0x00000001 0x00000000 0x00000000 0x00000000 > [ 1439.093196] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0x00000001 0x00000002 0x00000000 0x00000000 > [ 1439.093251] cx18-0: api: CX18_CPU_SET_MEDIAN_CORING cmd 0x2002000e > args 0x00000001 0x00000000 0x000000ff 0x00000000 0x000000ff > [ 1439.093311] cx18-0: api: CX18_CPU_SET_SPATIAL_FILTER_TYPE cmd > 0x2002000c args 0x00000001 0x00000001 0x00000001 > [ 1439.093368] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0x00000001 0x00000001 0x00000000 0x00000000 > [ 1439.093466] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0x00000001 0x00000000 0x00000000 0x00000000 > [ 1439.093530] cx18-0: api: CX18_CPU_SET_FILTER_PARAM cmd 0x20020009 > args 0x00000001 0x00000002 0x00000000 0x00000000 > [ 1439.093584] cx18-0: api: CX18_CPU_SET_SKIP_INPUT_FRAME cmd > 0x2002001f args 0x00000001 0x00000000 > [ 1439.093633] cx18-0: api: CX18_CPU_SET_VIDEO_MUTE cmd 0x20020013 > args 0x00000001 0x00808000 > [ 1439.093684] cx18-0: api: CX18_CPU_SET_MISC_PARAMETERS cmd > 0x20020015 args 0x00000001 0x00000007 0x00000000 0x00000000 > [ 1439.093739] cx18-0: api: CX18_CPU_DE_SET_MDL_ACK cmd 0x20040002 > args 0x00000001 0x00dc0c40 0x00dc0c48 > [ 1439.102576] cx18-0: api: CX18_CPU_CAPTURE_START cmd 0x20020002 > args 0x00000001 > [ 1446.654744] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 11990) > [ 1446.654757] cx18-0: api: incoming: req 0x00002ed6 ack 0x00002ed6 > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c50 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1460.770887] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 13494) while > processing > [ 1462.271840] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 13655) > [ 1462.271852] cx18-0: api: incoming: req 0x00003557 ack 0x00003557 > cmd 0x02040001 err 0x00000000 args 0x00000001 0x00dc0c48 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1468.290206] cx18-0: warning: failed to be awakened upon RPU > acknowledgment sending CX18_CPU_DE_SET_MDL; timed out waiting 20 msecs > [ 1473.784866] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 14887) while > processing > [ 1482.287056] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 15789) > [ 1482.287067] cx18-0: api: incoming: req 0x00003dad ack 0x00003dad > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c50 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1488.798573] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 16485) > [ 1488.798585] cx18-0: api: incoming: req 0x00004065 ack 0x00004065 > cmd 0x02040001 err 0x00000000 args 0x00000001 0x00dc0c48 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1507.416354] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 18463) > [ 1507.416365] cx18-0: api: incoming: req 0x0000481f ack 0x0000481f > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c58 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1510.620047] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 18802) while > processing > [ 1514.322277] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 19202) while > processing > [ 1535.844227] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 21490) > [ 1535.844239] cx18-0: api: incoming: req 0x000053f2 ack 0x000053f2 > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c50 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1542.453566] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 22194) > [ 1542.453577] cx18-0: api: incoming: req 0x000056b2 ack 0x000056b2 > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c58 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1558.067669] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 23843) > [ 1558.067680] cx18-0: api: incoming: req 0x00005d23 ack 0x00005d23 > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c50 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1565.474276] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 24628) > [ 1565.474288] cx18-0: api: incoming: req 0x00006034 ack 0x00006034 > cmd 0x02040001 err 0x00000000 args 0x00000000 0x00dc0c50 0x00000001 > 0x00000000 0x00000000 0x00000000 > [ 1573.382111] cx18-0: warning: Possibly falling behind: CPU > self-ack'ed our incoming CPU to EPU mailbox (sequence no. 25465) while > processing > > Let's change the channel again. Playback stops. log: > > [ 1696.430026] cx18-0: info: Input unchanged > [ 1696.433796] cx18-0: api: CX18_CPU_SET_AUDIO_MUTE cmd 0x20020014 > args 0x00000001 0x00000001 > [ 1696.435486] cx18-0: info: Mute > [ 1696.435491] cx18-0: info: v4l2 ioctl: set frequency 3092 > [ 1696.536031] cx18-0: api: CX18_CPU_SET_MISC_PARAMETERS cmd > 0x20020015 args 0x00000001 0x0000000c > [ 1696.536109] cx18-0: api: CX18_CPU_SET_AUDIO_MUTE cmd 0x20020014 > args 0x00000001 0x00000000 > [ 1696.556037] cx18-0: warning: sending CX18_CPU_SET_AUDIO_MUTE timed > out waiting 20 msecs for RPU acknowledgement > [ 1696.556045] cx18-0: info: Unmute > [ 1697.929472] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1697.940101] cx18-0: warning: sending CX18_CPU_DE_SET_MDL timed out > waiting 12 msecs for RPU acknowledgement > [ 1697.952041] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1697.964776] cx18-0: warning: sending CX18_CPU_DE_SET_MDL timed out > waiting 12 msecs for RPU acknowledgement > [ 1697.976055] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1697.988026] cx18-0: warning: sending CX18_CPU_DE_SET_MDL timed out > waiting 12 msecs for RPU acknowledgement > [ 1698.000042] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1698.012028] cx18-0: warning: sending CX18_CPU_DE_SET_MDL timed out > waiting 12 msecs for RPU acknowledgement > [ 1698.024027] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1698.036038] cx18-0: warning: sending CX18_CPU_DE_SET_MDL timed out > waiting 12 msecs for RPU acknowledgement > [ 1698.048035] cx18-0: mbox was found stuck busy when setting up for > CX18_CPU_DE_SET_MDL; clearing busy and trying to proceed > [ 1698.060025] cx18-0: warning: sending CX18_CPU_DE_SET_MDL timed out > waiting 12 msecs for RPU acknowledgement > > If I only enable the DVB tuner in MythTV and start up that way, I can > have digital tv just fine. Sound works for the digital channels too. > However, if I enable both analog and digital tuners and get into the > above error situation by trying to change a station on the analog > tuner, digital channels will not work after that anymore. > > Please advise. Any help will be appreciated. The APU in your CX23418 chip is not behaving properly. Only having problems with analog audio somewhat confirms that. 1. Check that your firmware images are correct - especially the APU image. 2. Pull out all of your PCI cards; blow the dust out of the slots; reinsert the cards; and try again. 3. Remove any non-critical PCI cards temporarily and test again. 4. To avoid a lot of disk activity while testing, don't test with MythTV; test with mplayer: $ ivtv-tune -d /dev/video0 -c 4 <---- Channel 4 $ mplayer /dev/video0 -cache 8192 5. Check with a Windows setup, ideally in the same machine, to determine if the HVR-1600 could be defective. Regards, Andy > > Thank you, > > Michiel van Wessem > _______________________________________________ ivtv-users mailing list [email protected] http://ivtvdriver.org/mailman/listinfo/ivtv-users
