Re: r329501 devd doesn't find USB devices

From: Ian FREISLICH <ian.freislich_at_capeaugusta.com>
Date: Sun, 18 Feb 2018 18:45:10 -0500
-- 
Ian Freislich
(M) +1 404 574 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 <mailto: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
>>     <mailto: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
>>>         <mailto: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.

Ian

-- 
Received on Sun Feb 18 2018 - 22:45:15 UTC

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