[ivtv-users] MPEGRec(/dev/video0) Error: select timeout - ivtv driver has stopped responding

[Andy Walls]

On Wed, 2009-07-15 at 19:23 -0400, Michael Cook wrote:
> I recently moved my pvr-150 from one computer to another, and after
> having done that I found that mythtv would fail recording
> occasionally, about once a day or so I would have to reboot.  The
> error messages that appeared in /var/log were like this:
> 
>   ivtv0: All encoder MPG stream buffers are full. Dropping data.
>   ivtv0: Cause: the application is not reading fast enough.
> 
> and
> 
>   MPEGRec(/dev/video0) Error: select timeout - ivtv driver has stopped responding
> 
> After googling those error messages for a while, it occurred to me
> to try moving my pvr-150 card from one pci slot to another.  After I
> did that, the failures seem to have stopped (after four days, no
> failures).
> 
> Is this information interesting?

Sort of.  It would be more interesting with the output of

$ cat /proc/interrupts
$ /sbin/lspci -vvv

So we can see what other Linux driver may be affecting the ivtv driver's
interrupt service.

Regards,
Andy


> Here's the change in the dmesg output from before the failures until
> after the slot change.
> 
> --- dmesg.0	2009-07-13 10:49:02.832870506 -0400
> +++ dmesg	2009-07-13 10:49:02.836869525 -0400
> @@ -118,7 +118,7 @@
>  Initializing CPU#0
>  PID hash table entries: 4096 (order: 12, 16384 bytes)
>  TSC: PIT calibration matches PMTIMER. 1 loops
> -Detected 2399.565 MHz processor.
> +Detected 2399.556 MHz processor.
>  Console: colour VGA+ 80x25
>  console [tty0] enabled
>  Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
> @@ -137,7 +137,7 @@
>        .text : 0xc0100000 - 0xc0503b9f   (4110 kB)
>  Checking if this processor honours the WP bit even in supervisor mode...Ok.
>  SLUB: Genslabs=12, HWalign=128, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
> -Calibrating delay loop (skipped), value calculated using timer frequency.. 4799.13 BogoMIPS (lpj=9598260)
> +Calibrating delay loop (skipped), value calculated using timer frequency.. 4799.11 BogoMIPS (lpj=9598224)
>  Security Framework initialized
>  SELinux:  Disabled at boot.
>  AppArmor: AppArmor initialized
> @@ -156,11 +156,11 @@
>  ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
>  CPU0: Intel(R) Pentium(R) 4 CPU 2.40GHz stepping 07
>  Brought up 1 CPUs
> -Total of 1 processors activated (4799.13 BogoMIPS).
> +Total of 1 processors activated (4799.11 BogoMIPS).
>  CPU0 attaching NULL sched-domain.
>  net_namespace: 776 bytes
>  Booting paravirtualized kernel on bare hardware
> -Time: 13:41:43  Date: 07/12/09
> +Time: 18:38:07  Date: 07/12/09
>  regulator: core version 0.5
>  NET: Registered protocol family 16
>  EISA bus registered
> @@ -201,7 +201,7 @@
>  pci 0000:00:1f.5: reg 1c 32bit mmio: [0xffa00000-0xffa000ff]
>  pci 0000:00:1f.5: PME# supported from D0 D3hot D3cold
>  pci 0000:00:1f.5: PME# disabled
> -pci 0000:01:07.0: reg 10 32bit mmio: [0xf8000000-0xfbffffff]
> +pci 0000:01:08.0: reg 10 32bit mmio: [0xf8000000-0xfbffffff]
>  pci 0000:01:0c.0: reg 10 32bit mmio: [0xff8e0000-0xff8fffff]
>  pci 0000:01:0c.0: reg 18 io port: [0xecc0-0xecff]
>  pci 0000:01:0c.0: PME# supported from D0 D3hot D3cold
> @@ -282,7 +282,7 @@
>  Simple Boot Flag at 0x7a set to 0x1
>  cpufreq: No nForce2 chipset.
>  audit: initializing netlink socket (disabled)
> -type=2000 audit(1247406103.632:1): initialized
> +type=2000 audit(1247423887.632:1): initialized
>  highmem bounce pool size: 64 pages
>  HugeTLB registered 4 MB page size, pre-allocated 0 pages
>  VFS: Disk quotas dquot_6.5.1
> @@ -410,8 +410,8 @@
>  Bluetooth: RFCOMM ver 1.10
>  Using IPI No-Shortcut mode
>  registered taskstats version 1
> -  Magic number: 1:418:685
> -rtc_cmos 00:05: setting system clock to 2009-07-12 13:41:45 UTC (1247406105)
> +  Magic number: 1:398:645
> +rtc_cmos 00:05: setting system clock to 2009-07-12 18:38:09 UTC (1247423889)
>  BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
>  EDD information not available.
>  Freeing unused kernel memory: 532k freed
> @@ -466,13 +466,13 @@
>  iTCO_wdt: failed to reset NO_REBOOT flag, reboot disabled by hardware
>  iTCO_wdt: No card detected
>  input: PC Speaker as /devices/platform/pcspkr/input/input4
> -synaptics was reset on resume, see synaptics_resume_reset if you have trouble on resume
>  Linux video capture interface: v2.00
> -dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
> -psmouse serio1: ID: 00 00 3c<6>ivtv:  Start initialization, version 1.4.0
> +synaptics was reset on resume, see synaptics_resume_reset if you have trouble on resume
> +psmouse serio1: ID: 00 00 3c<6>dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
> +ivtv:  Start initialization, version 1.4.0
>  ivtv0: Initializing card #0
>  ivtv0: Autodetected Hauppauge card (cx23416 based)
> -ivtv 0000:01:07.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
> +ivtv 0000:01:08.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
>  tveeprom 0-0050: Hauppauge model 26152, rev E5B2, serial# 10317012
>  tveeprom 0-0050: tuner model is TCL M2523_5N_E (idx 112, type 50)
>  tveeprom 0-0050: TV standards NTSC(M) (eeprom 0x08)
> @@ -492,11 +492,11 @@
>  ivtv0: Registered device video24 for encoder PCM (320 kB)
>  ivtv0: Initialized card #0: Hauppauge WinTV PVR-150
>  ivtv:  End initialization
> +ppdev: user-space parallel port driver
> +input: ImPS/2 Logitech Wheel Mouse as /devices/platform/i8042/serio1/input/input5
>  Intel ICH 0000:00:1f.5: PCI INT B -> GSI 17 (level, low) -> IRQ 17
>  Intel ICH 0000:00:1f.5: setting latency timer to 64
> -input: ImPS/2 Logitech Wheel Mouse as /devices/platform/i8042/serio1/input/input5
> -ppdev: user-space parallel port driver
> -intel8x0_measure_ac97_clock: measured 54894 usecs
> +intel8x0_measure_ac97_clock: measured 54847 usecs
>  intel8x0: clocking to 48000
>  lp0: using parport0 (interrupt-driven).
>  Adding 497972k swap on /dev/sda5.  Priority:-1 extents:1 across:497972k
> @@ -507,22 +507,22 @@
>  Ending clean XFS mount for filesystem: sda6
>  XFS mounting filesystem sdb1
>  Ending clean XFS mount for filesystem: sdb1
> -type=1505 audit(1247406122.092:2): operation="profile_load" name="/sbin/dhclient-script" name2="default" pid=1945
> -type=1505 audit(1247406122.092:3): operation="profile_load" name="/sbin/dhclient3" name2="default" pid=1945
> -type=1505 audit(1247406122.092:4): operation="profile_load" name="/usr/lib/NetworkManager/nm-dhcp-client.action" name2="default" pid=1945
> -type=1505 audit(1247406122.092:5): operation="profile_load" name="/usr/lib/connman/scripts/dhclient-script" name2="default" pid=1945
> -type=1505 audit(1247406122.484:6): operation="profile_load" name="/usr/lib/cups/backend/cups-pdf" name2="default" pid=1950
> -type=1505 audit(1247406122.500:7): operation="profile_load" name="/usr/sbin/cupsd" name2="default" pid=1950
> -type=1505 audit(1247406122.608:8): operation="profile_load" name="/usr/sbin/dhcpd3" name2="default" pid=1954
> -type=1505 audit(1247406122.720:9): operation="profile_load" name="/usr/sbin/mysqld" name2="default" pid=1958
> -type=1505 audit(1247406122.816:10): operation="profile_load" name="/usr/sbin/mysqld-akonadi" name2="default" pid=1962
> -type=1505 audit(1247406122.908:11): operation="profile_load" name="/usr/sbin/tcpdump" name2="default" pid=1966
> +type=1505 audit(1247423906.388:2): operation="profile_load" name="/sbin/dhclient-script" name2="default" pid=1944
> +type=1505 audit(1247423906.388:3): operation="profile_load" name="/sbin/dhclient3" name2="default" pid=1944
> +type=1505 audit(1247423906.388:4): operation="profile_load" name="/usr/lib/NetworkManager/nm-dhcp-client.action" name2="default" pid=1944
> +type=1505 audit(1247423906.388:5): operation="profile_load" name="/usr/lib/connman/scripts/dhclient-script" name2="default" pid=1944
> +type=1505 audit(1247423906.792:6): operation="profile_load" name="/usr/lib/cups/backend/cups-pdf" name2="default" pid=1949
> +type=1505 audit(1247423906.792:7): operation="profile_load" name="/usr/sbin/cupsd" name2="default" pid=1949
> +type=1505 audit(1247423906.912:8): operation="profile_load" name="/usr/sbin/dhcpd3" name2="default" pid=1953
> +type=1505 audit(1247423907.016:9): operation="profile_load" name="/usr/sbin/mysqld" name2="default" pid=1957
> +type=1505 audit(1247423907.112:10): operation="profile_load" name="/usr/sbin/mysqld-akonadi" name2="default" pid=1961
> +type=1505 audit(1247423907.212:11): operation="profile_load" name="/usr/sbin/tcpdump" name2="default" pid=1965
>  RPC: Registered udp transport module.
>  RPC: Registered tcp transport module.
>  Installing knfsd (copyright (C) 1996 okir at monad.swb.de).
>  NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
>  NFSD: starting 90-second grace period
> -ivtv 0000:01:07.0: firmware: requesting v4l-cx2341x-enc.fw
> +ivtv 0000:01:08.0: firmware: requesting v4l-cx2341x-enc.fw
>  ivtv0: Loaded v4l-cx2341x-enc.fw firmware (376836 bytes)
>  ivtv0: Encoder revision: 0x02060039
>  cx25840 0-0044: firmware: requesting v4l-cx25840.fw
> 
> _______________________________________________
> ivtv-users mailing list
> ivtv-users at ivtvdriver.org
> http://ivtvdriver.org/mailman/listinfo/ivtv-users
>