Re: r329501 devd doesn't find USB devices

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

>
> --
> Ian Freislich
> (M) +1 404 574 0228 <(404)%20574-0228>
>
> On 02/18/18 18:17, Warner Losh wrote:
>
>
>
> 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).
>
>
> No difference:
>
> Feb 18 18:34:33 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.5.0'
> Feb 18 18:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=ugen0.5'
> Feb 18 18:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.5.1'
> Feb 18 18:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 zen devd: Processing event '!system=DEVFS subsystem=CDEV
> type=CREATE cdev=usb/0.5.2'
> Feb 18 18:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 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:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 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:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 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:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing notify event
> Feb 18 18:34:33 zen devd: Popping table
> Feb 18 18:34:33 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:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing nomatch event
> Feb 18 18:34:33 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:34:33 zen devd: Popping table
> Feb 18 18:34:33 zen devd: Processing event '? at bus=0 hubaddr=1 port=2
> devaddr=5 interface=1 ugen=ugen0.5 vendor=0x24ae product=0x2000
> devclass=0x00 devsubclass=0x00 devproto=0x00 sernum="" release=0x1001
> mode=host intclass=0x03 intsubclass=0x01 intprotocol=0x02 on uhub0'
> Feb 18 18:34:33 zen devd: Pushing table
> Feb 18 18:34:33 zen devd: Processing nomatch event
> Feb 18 18:34:33 zen devd: Executing '/etc/rc.d/devmatch start '? at bus=0
> hubaddr=1 port=2 devaddr=5 interface=1 ugen=ugen0.5 vendor=0x24ae
> product=0x2000 devclass=0x00 devsubclass=0x00 devproto=0x00 sernum=""
> release=0x1001 mode=host intclass=0x03 intsubclass=0x01 intprotocol=0x02 on
> uhub0''
> Feb 18 18:34:34 zen devd: Popping table
>
> FWIW, this laptop's USB works but is quirky in FreeBSD.  It has 2
> thunderbolt ports both of which work under Windows.  Only one works under
> BSD for HDMI out but doesn't work as USB so nothing detects in it except
> the the power+hdmi+usb dongle that came with it.  The USB-3 port works in
> the dongle though when connected to this port.  USB+HDMI dongle doesn't
> work on the first thunderbolt port but USB devices plugged in this port
> work.  I can charge on both thunderbolt ports. The third type-C port works
> as expected.
>

OK. I'll delve deeper tonight.

Can you do a kldstat to see if the right .ko's got loaded? If so that's one
problem, if not it's a different problem. Thanks for your patience as I
iron out this new feature.

Warner
Received on Sun Feb 18 2018 - 22:49:04 UTC

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