On 5/30/05, Richard Fish <[EMAIL PROTECTED]> wrote: > Unless someone has a better idea, try turning on udev logging in > /etc/udev/udev.conf, and compare the results from booting to what > happens when you rmmod/insmod driver. The log message will appear in > /var/log/messages. > > -Richard
OK, here goes. The first batch is the reboot. I'll include the part where the machine is going down in addition to the actual boot up. I captured video after this boot. The video was bad: May 30 10:34:37 [init] Switching to runlevel: 6 May 30 10:34:37 [udev] removing device node '/dev/vcc/2' May 30 10:34:37 [udev] removing device node '/dev/vcc/a2' May 30 10:34:37 [udev] removing device node '/dev/vcc/3' May 30 10:34:37 [udev] removing device node '/dev/vcc/a3' May 30 10:34:37 [udev] removing device node '/dev/vcc/4' May 30 10:34:37 [udev] removing device node '/dev/vcc/a4' May 30 10:34:37 [udev] removing device node '/dev/vcc/5' May 30 10:34:37 [udev] removing device node '/dev/vcc/a5' May 30 10:34:37 [udev] removing device node '/dev/vcc/6' May 30 10:34:37 [udev] removing device node '/dev/vcc/a6' May 30 10:34:39 [su(pam_unix)] session closed for user root May 30 10:34:40 [PAM-env] Unknown PAM_ITEM: <DISPLAY> May 30 10:34:40 [sshd] PAM pam_putenv: delete non-existent entry; DISPLAY May 30 10:34:40 [PAM-env] Unknown PAM_ITEM: <XAUTHORITY> May 30 10:34:40 [sshd] PAM pam_putenv: delete non-existent entry; XAUTHORITY May 30 10:34:40 [sshd(pam_unix)] session closed for user mark May 30 10:34:42 [sshd] Received signal 15; terminating. May 30 10:36:13 [kernel] ACPI: PCI interrupt 0000:02:01.0[A] -> GSI 5 (level, low) -> IRQ 5 May 30 10:36:14 [apcupsd] apcupsd 3.10.15 (04 August 2004) gentoo startup succeeded May 30 10:36:14 [apcupsd] NIS server startup succeeded May 30 10:36:20 [noip2] v2.1.1 daemon started with NAT enabled_ May 30 10:36:21 [ntpd] ntpd [EMAIL PROTECTED] Mon Feb 21 05:44:47 PST 2005 (1) May 30 10:36:21 [ntpd] precision = 1.000 usec May 30 10:36:21 [ntpd] no IPv6 interfaces found May 30 10:36:21 [ntpd] kernel time sync status 0040 May 30 10:36:22 [sshd] Server listening on 0.0.0.0 port 22. May 30 10:36:22 [cron] (CRON) STARTUP (V5.0) May 30 10:36:22 [init] Activating demand-procedures for 'A' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs6' becomes 'vcc/%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/6' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa6' becomes 'vcc/a%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/a6' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs2' becomes 'vcc/%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/2' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa2' becomes 'vcc/a%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/a2' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs3' becomes 'vcc/%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/3' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa3' becomes 'vcc/a%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/a3' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs4' becomes 'vcc/%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/4' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa4' becomes 'vcc/a%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/a4' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs5' becomes 'vcc/%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/5' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:22 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa5' becomes 'vcc/a%n' May 30 10:36:22 [udev] creating device node '/dev/vcc/a5' May 30 10:36:24 [noip2] thevillas.hopto.org set to 69.175.210.33 May 30 10:36:26 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:26 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs7' becomes 'vcc/%n' May 30 10:36:26 [udev] creating device node '/dev/vcc/7' May 30 10:36:26 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:26 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa7' becomes 'vcc/a%n' May 30 10:36:26 [udev] creating device node '/dev/vcc/a7' May 30 10:36:28 [udev] removing device node '/dev/vcc/7' May 30 10:36:28 [udev] removing device node '/dev/vcc/a7' May 30 10:36:28 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, added symlink '%k' May 30 10:36:28 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[159]' applied, 'vcsa7' becomes 'vcc/a%n' May 30 10:36:28 [udev] creating device node '/dev/vcc/a7' May 30 10:36:28 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, added symlink '%k' May 30 10:36:28 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[157]' applied, 'vcs7' becomes 'vcc/%n' May 30 10:36:28 [udev] creating device node '/dev/vcc/7' May 30 10:36:31 [kernel] cx25840: decoder disable output May 30 10:36:31 [kernel] cx25840: decoder enable output May 30 10:36:31 [kernel] cx25840: decoder disable output May 30 10:36:31 [kernel] cx25840: decoder enable output May 30 10:38:26 [sshd] Accepted publickey for mark from xx.xx.xx.xx port 37571 ssh2 May 30 10:38:26 [sshd(pam_unix)] session opened for user mark by (uid=0) At this point video was captured and failed. Next I do the rmmod and modprobe: May 30 10:43:22 [kernel] ivtv: Removing Card #0. May 30 10:43:23 [udev] removing device node '/dev/v4l/video0' May 30 10:43:23 [udev] removing device node '/dev/v4l/video32' May 30 10:43:23 [udev] removing device node '/dev/v4l/vbi0' May 30 10:43:23 [udev] removing device node '/dev/v4l/video24' May 30 10:43:23 [udev] removing device node '/dev/v4l/radio0' May 30 10:43:23 [kernel] ivtv: Removed WinTV PVR 150, card #0 May 30 10:43:30 [kernel] ivtv: ==================== START INIT IVTV ==================== May 30 10:43:30 [kernel] ivtv: version 0.3.3 (k) loading May 30 10:43:30 [kernel] ivtv: Linux version: 2.6.11-gentoo-r9 preempt K7 4KSTACKS gcc-3.3 May 30 10:43:30 [kernel] ivtv: In case of problems please include the debug info May 30 10:43:30 [kernel] ivtv: between the START INIT IVTV and END INIT IVTV lines when May 30 10:43:30 [kernel] ivtv: mailing the ivtv-devel mailinglist. May 30 10:43:30 [kernel] ivtv: Autodetected WinTV PVR 150 card May 30 10:43:30 [kernel] ivtv: Found an iTVC16 based chip May 30 10:43:30 [kernel] ACPI: PCI interrupt 0000:01:0a.0[A] -> GSI 11 (level, low) -> IRQ 11 May 30 10:43:30 [kernel] ivtv: XXX PCI device: 0x01e0 vendor: 0x10de May 30 10:43:30 [kernel] tuner: chip found at addr 0xc2 i2c-bus ivtv i2c driver #0 May 30 10:43:30 [kernel] ivtv: i2c attach [client=(tuner unset),ok] May 30 10:43:30 [kernel] saa7115: starting probe for adapter ivtv i2c driver #0 (0x10005) May 30 10:43:30 [kernel] tveeprom: Hauppauge: model = 26032, rev = C199, serial# = 2978579 May 30 10:43:30 [kernel] tveeprom: tuner = TCL 2002N 5H (idx = 99, type = 50) May 30 10:43:30 [kernel] tveeprom: tuner fmt = NTSC(M) (eeprom = 0x08, v4l2 = 0x00001000) May 30 10:43:30 [kernel] tveeprom: audio_processor = TDA9850 (type = 3) May 30 10:43:30 [kernel] ivtv: i2c attach [client=tveeprom[50],ok] May 30 10:43:31 [kernel] cx25840: Failed to md5 digester, skipping it. May 30 10:43:31 [kernel] cx25840: Failed to load sha1 digester, skipping it. May 30 10:43:35 [kernel] cx25840: FW image '/lib/modules/HcwMakoA.ROM' of size 14264 loaded. May 30 10:43:35 [kernel] ivtv: i2c attach [client=cx25840[50],ok] May 30 10:43:35 [kernel] ivtv: i2c attach [client=wm8775[50],ok] May 30 10:43:35 [kernel] ivtv: Tuner Type 50, Tuner formats 0x00001000, Radio: yes, Model 0x008d1659, Revision 0x00000000 May 30 10:43:35 [kernel] ivtv: NTSC tuner detected May 30 10:43:35 [kernel] ivtv: Radio detected May 30 10:43:36 [kernel] ivtv: Encoder revision: 0x02040011 May 30 10:43:36 [kernel] ivtv: Configuring WinTV PVR 150 card with 5 streams May 30 10:43:36 [kernel] ivtv: Registered v4l2 device, streamtype 0 minor 0 May 30 10:43:36 [kernel] ivtv: Create DMA stream 0 using 1024 16384 byte buffers 0 kbytes total May 30 10:43:36 [kernel] ivtv: Allocate DMA stream 0 using 1024 16384 byte buffers 16777216 kbytes totalMay 30 10:43:36 [kernel] ivtv: Registered v4l2 device, streamtype 1 minor 32 May 30 10:43:36 [kernel] ivtv: Create DMA stream 1 May 30 10:43:36 [kernel] ivtv: Allocate DMA stream 1 May 30 10:43:36 [kernel] ivtv: Registered v4l2 device, streamtype 2 minor 224 May 30 10:43:36 [kernel] ivtv: Create DMA stream 2 using 121 34632 byte buffers 0 kbytes total May 30 10:43:36 [kernel] ivtv: Allocate DMA stream 2 using 121 34632 byte buffers 4194304 kbytes total May 30 10:43:36 [kernel] ivtv: Registered v4l2 device, streamtype 3 minor 24 May 30 10:43:36 [kernel] ivtv: Create DMA stream 3 using 455 4608 byte buffers 0 kbytes total May 30 10:43:36 [kernel] ivtv: Allocate DMA stream 3 using 455 4608 byte buffers 2097152 kbytes total May 30 10:43:36 [kernel] ivtv: Registered v4l2 device, streamtype 4 minor 64 May 30 10:43:36 [kernel] ivtv: Create stream 4 May 30 10:43:36 [kernel] ivtv: Allocate stream 4 May 30 10:43:36 [kernel] ivtv: Setting Tuner 50 May 30 10:43:36 [kernel] tuner: type set to 50 (TCL 2002N) by ivtv i2c driver #0 May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[179]' applied, added symlink 'video%n' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[179]' applied, 'video0' becomes 'v4l/video%n' May 30 10:43:36 [udev] creating device node '/dev/v4l/video0' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[179]' applied, added symlink 'video%n' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[179]' applied, 'video32' becomes 'v4l/video%n' May 30 10:43:36 [udev] creating device node '/dev/v4l/video32' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[181]' applied, added symlink 'vbi%n' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[181]' applied, 'vbi0' becomes 'v4l/vbi%n' May 30 10:43:36 [udev] creating device node '/dev/v4l/vbi0' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[179]' applied, added symlink 'video%n' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[179]' applied, 'video24' becomes 'v4l/video%n' May 30 10:43:36 [udev] creating device node '/dev/v4l/video24' May 30 10:43:36 [udev] configured rule in '/etc/udev/rules.d/50-udev.rules[180]' applied, 'radio0' becomes 'v4l/radio%n' May 30 10:43:36 [udev] creating device node '/dev/v4l/radio0' May 30 10:43:36 [kernel] ivtv: ivtv_enc_thread: pid = 9341, itv = 0xe0ff0720 May 30 10:43:36 [kernel] ivtv: Switching standard to NTSC. May 30 10:43:36 [kernel] ivtv: i2c client addr: 0x43 not found! May 30 10:43:36 [kernel] ivtv: Initialized WinTV PVR 150, card #0 May 30 10:43:36 [kernel] ivtv: ==================== END INIT IVTV ==================== May 30 10:43:45 [kernel] cx25840: decoder disable output May 30 10:43:45 [kernel] cx25840: decoder enable output At this point I ssh into the machine again and capture video. It works fine. I think the list of events is quite different. I don't really know where to start looking. thanks in advance, Mark -- gentoo-user@gentoo.org mailing list
