Re: r329501 devd doesn't find USB devices

From: Warner Losh <imp_at_bsdimp.com>
Date: Sun, 18 Feb 2018 16:17:53 -0700
On Sun, Feb 18, 2018 at 4:12 PM, Ian FREISLICH <
ian.freislich_at_capeaugusta.com> wrote:

> On 02/18/18 15:09, Warner Losh wrote:
>
> On Sat, Feb 17, 2018 at 9:14 PM, Ian FREISLICH <
> ian.freislich_at_capeaugusta.com> wrote:
>
>> On 02/17/18 22:48, Warner Losh wrote:
>>
>> On Feb 17, 2018 8:24 PM, "Ian FREISLICH" <ian.freislich_at_capeaugusta.com>
>> wrote:
>>
>> Hi
>>
>> Since devmatch some of my USB devices no longer get their drivers
>> loaded.  It's not clear from UPDATING whether I needed to do anything
>> beyond building and installing kernel and world as well as updating
>> /etc.  There was reference to removing /etc/devd/usb.conf in another
>> thread but its presence or lack thereof makes no difference.
>>
>>
>> I assume you've fully updated including /etc.
>>
>>
>> In as much as 'mergemaster -Ui' fully updates /etc
>>
>> If you can uncomment the devd lines in syslog.conf, touch
>> /var/log/devd.log and reboot. Once you are up again, please send me
>> /var/log/devd.conf.
>>
>>
>> Assuming you mean these lines:
>>
>> !devd
>> *.>=notice                                      /var/log/devd.log
>>
>> devd produced zero logs on reboot and restart.
>>
>
> There should be a lot of output... one line per device that's attached...
> Did you create /var/log/devd.log before reboot? Is your /dev/log persistent
> across boots?
>
>
> Lots of output after I changed the priority from 'notice' to 'debug' in
> syslogd.conf.  Might want to fix that in src/etc/syslogd.conf.
>
> 1. Startup:
>
> Feb 18 17:43:44 zen devd: Pushing table
> Feb 18 17:43:44 zen devd: Parsing /etc/devd.conf
> Feb 18 17:43:44 zen devd: Parsing files in /etc/devd
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/devmatch.conf
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/asus.conf
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/hyperv.conf
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/uath.conf
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/ulpt.conf
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/usb.conf
> Feb 18 17:43:44 zen devd: Parsing /etc/devd/zfs.conf
> Feb 18 17:43:44 zen devd: Parsing files in /usr/local/etc/devd
> Feb 18 17:43:44 zen devd: Parsing /usr/local/etc/devd/cups.conf
> Feb 18 17:43:44 zen devd: Parsing /usr/local/etc/devd/webcamd.conf
> Feb 18 17:43:44 zen devd: Calling daemon
>
>
> 2. Inserting the USB-C NIC:
>
> Feb 18 18:05:53 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.0'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=ugen0.6'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.1'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.2'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.3'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '!system=USB subsystem=DEVICE
> type=ATTACH ugen=ugen0.6 cdev=ugen0.6 vendor=0x0bda product=0x8153
> devclass=0x00 devsubclass=0x00 sernum="000001" release=0x3000 mode=host
> port=13 parent=ugen0.1'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '!system=USB
> subsystem=INTERFACE type=ATTACH ugen=ugen0.6 cdev=ugen0.6 vendor=0x0bda
> product=0x8153 devclass=0x00 devsubclass=0x00 sernum="000001"
> release=0x3000 mode=host interface=0 endpoints=3 intclass=0xff
> intsubclass=0xff intprotocol=0x00'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing notify event
> Feb 18 18:05:53 zen devd: Executing '/usr/local/etc/rc.d/webcamd start
> ugen0.6'
> Feb 18 18:05:53 zen devd: Popping table
> Feb 18 18:05:53 zen devd: Processing event '? at bus=0 hubaddr=1 port=13
> devaddr=6 interface=0 ugen=ugen0.6 vendor=0x0bda product=0x8153
> devclass=0x00 devsubclass=0x00 devproto=0x00 sernum="000001" release=0x3000
> mode=host intclass=0xff intsubclass=0xff intprotocol=0x00 on uhub0'
> Feb 18 18:05:53 zen devd: Pushing table
> Feb 18 18:05:53 zen devd: Processing nomatch event
> Feb 18 18:05:53 zen devd: Executing '/etc/rc.d/devmatch start '? at bus=0
> hubaddr=1 port=13 devaddr=6 interface=0 ugen=ugen0.6 vendor=0x0bda
> product=0x8153 devclass=0x00 devsubclass=0x00 devproto=0x00 sernum="000001"
> release=0x3000 mode=host intclass=0xff intsubclass=0xff intprotocol=0x00 on
> uhub0''
> Feb 18 18:05:53 zen devd: Popping table
>
>
> 3. Insert USB-3 drive:
>
> Feb 18 18:09:38 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.0'
> Feb 18 18:09:38 zen devd: Pushing table
> Feb 18 18:09:38 zen devd: Processing notify event
> Feb 18 18:09:38 zen devd: Popping table
> Feb 18 18:09:38 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=ugen0.6'
> Feb 18 18:09:38 zen devd: Pushing table
> Feb 18 18:09:38 zen devd: Processing notify event
> Feb 18 18:09:38 zen devd: Popping table
> Feb 18 18:09:38 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.1'
> Feb 18 18:09:38 zen devd: Pushing table
> Feb 18 18:09:38 zen devd: Processing notify event
> Feb 18 18:09:38 zen devd: Popping table
> Feb 18 18:09:38 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.2'
> Feb 18 18:09:38 zen devd: Pushing table
> Feb 18 18:09:38 zen devd: Processing notify event
> Feb 18 18:09:38 zen devd: Popping table
> Feb 18 18:09:38 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.3'
> Feb 18 18:09:38 zen devd: Pushing table
> Feb 18 18:09:38 zen devd: Processing notify event
> Feb 18 18:09:38 zen devd: Popping table
> Feb 18 18:09:38 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.6.4'
> Feb 18 18:09:38 zen devd: Pushing table
> Feb 18 18:09:38 zen devd: Processing notify event
> Feb 18 18:09:38 zen devd: Popping table
> Feb 18 18:09:40 zen devd: Processing event '!system=USB subsystem=DEVICE
> type=ATTACH ugen=ugen0.6 cdev=ugen0.6 vendor=0x0bc2 product=0xab24
> devclass=0x00 devsubclass=0x00 sernum="NA7W30KM" release=0x0100 mode=host
> port=13 parent=ugen0.1'
> Feb 18 18:09:40 zen devd: Pushing table
> Feb 18 18:09:40 zen devd: Processing notify event
> Feb 18 18:09:40 zen devd: Popping table
> Feb 18 18:09:40 zen devd: Processing event '!system=USB
> subsystem=INTERFACE type=ATTACH ugen=ugen0.6 cdev=ugen0.6 vendor=0x0bc2
> product=0xab24 devclass=0x00 devsubclass=0x00 sernum="NA7W30KM"
> release=0x0100 mode=host interface=0 endpoints=2 intclass=0x08
> intsubclass=0x06 intprotocol=0x50'
> Feb 18 18:09:40 zen devd: Pushing table
> Feb 18 18:09:40 zen devd: Processing notify event
> Feb 18 18:09:40 zen devd: Popping table
> Feb 18 18:09:40 zen devd: Processing event '? at bus=0 hubaddr=1 port=13
> devaddr=6 interface=0 ugen=ugen0.6 vendor=0x0bc2 product=0xab24
> devclass=0x00 devsubclass=0x00 devproto=0x00 sernum="NA7W30KM"
> release=0x0100 mode=host intclass=0x08 intsubclass=0x06 intprotocol=0x50 on
> uhub0'
> Feb 18 18:09:40 zen devd: Pushing table
> Feb 18 18:09:40 zen devd: Processing nomatch event
> Feb 18 18:09:40 zen devd: Executing '/etc/rc.d/devmatch start '? at bus=0
> hubaddr=1 port=13 devaddr=6 interface=0 ugen=ugen0.6 vendor=0x0bc2
> product=0xab24 devclass=0x00 devsubclass=0x00 devproto=0x00
> sernum="NA7W30KM" release=0x0100 mode=host intclass=0x08 intsubclass=0x06
> intprotocol=0x50 on uhub0''
> Feb 18 18:09:40 zen devd: Popping table
>
>
> 4. Inserting the keyboard/mouse:
>
> Feb 18 18:11:04 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.5.0'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=ugen0.5'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.5.1'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.5.2'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '!system=USB subsystem=DEVICE
> type=ATTACH ugen=ugen0.5 cdev=ugen0.5 vendor=0x24ae product=0x2000
> devclass=0x00 devsubclass=0x00 sernum="" release=0x1001 mode=host port=2
> parent=ugen0.1'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '!system=USB
> subsystem=INTERFACE type=ATTACH ugen=ugen0.5 cdev=ugen0.5 vendor=0x24ae
> product=0x2000 devclass=0x00 devsubclass=0x00 sernum="" release=0x1001
> mode=host interface=0 endpoints=1 intclass=0x03 intsubclass=0x01
> intprotocol=0x01'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '!system=USB
> subsystem=INTERFACE type=ATTACH ugen=ugen0.5 cdev=ugen0.5 vendor=0x24ae
> product=0x2000 devclass=0x00 devsubclass=0x00 sernum="" release=0x1001
> mode=host interface=1 endpoints=1 intclass=0x03 intsubclass=0x01
> intprotocol=0x02'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing notify event
> Feb 18 18:11:04 zen devd: Popping table
> Feb 18 18:11:04 zen devd: Processing event '? at bus=0 hubaddr=1 port=2
> devaddr=5 interface=0 ugen=ugen0.5 vendor=0x24ae product=0x2000
> devclass=0x00 devsubclass=0x00 devproto=0x00 sernum="" release=0x1001
> mode=host intclass=0x03 intsubclass=0x01 intprotocol=0x01 on uhub0'
> Feb 18 18:11:04 zen devd: Pushing table
> Feb 18 18:11:04 zen devd: Processing nomatch event
> Feb 18 18:11:04 zen devd: Executing '/etc/rc.d/devmatch start '? at bus=0
> hubaddr=1 port=2 devaddr=5 interface=0 ugen=ugen0.5 vendor=0x24ae
> product=0x2000 devclass=0x00 devsubclass=0x00 devproto=0x00 sernum=""
> release=0x1001 mode=host intclass=0x03 intsubclass=0x01 intprotocol=0x01 on
> uhub0''
> Feb 18 18:11:04 zen devd: Popping table
>
>
That's better... I think you might be hitting the same bug I've been
hitting on my system...

It looks like we're calling devmatch, but it isn't seeing the modules to
load. There's still a mismatch between things in the USB code (likely my
fault, I thought I'd fixed them all: either I missed one or broke
something). Try running with r329538 (it's just a new devmatch, so all you
need to do is install just it).

Warner
Received on Sun Feb 18 2018 - 22:17:56 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:41:15 UTC