Re: r329501 devd doesn't find USB devices

From: Ian FREISLICH <ian.freislich_at_capeaugusta.com>
Date: Sun, 18 Feb 2018 18:58:04 -0500
On 02/18/18 18:49, Warner Losh wrote:
> On Sun, Feb 18, 2018 at 4:45 PM, Ian FREISLICH
> <ian.freislich_at_capeaugusta.com <mailto:ian.freislich_at_capeaugusta.com>>
> wrote:
>
>     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.co <http://webcamd.co>nf
>>         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.

No additional modules are loaded.  No worries about the issue - I have a
work around until it's fixed.

Ian


-- 
Received on Sun Feb 18 2018 - 22:58:09 UTC

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