Package: libhpmud0
Version: 3.22.10+dfsg0-2
Control: affects -1 hplip hplip-data colord
In my journal I discovered an error
colord-sane[1000]: io/hpmud/musb.c 2101: Invalid usb_open: Permission denied
The part “io/hpmud/musb.c 2101: Invalid usb_open: Permission denied” is red.
The message appears during boot; a hopefully relevant part of the journal is
attached. The computer has many USB-A ports, and by default lots of stuff is
connected to them (a mouse, a keyboard, an HP printer, an HP all-in-one (used
only as a scanner), and sometimes an external USB thumb drive; the printer and
the scanner usually sleep or are off at boot). It would be great if we could
see an indication of which port was tried to get opened (the identifier of the
USB port is unlikely to be a privacy concern) because connecting and
disconnecting stuff and rebooting for the purpose of debugging may be
difficult. (In my special case, plugging/unplugging the cables on the
particular computer is physically tedious, and rebooting takes lots of time.
Other folks might find rebooting difficult, e.g., in case of highly available
servers.)
Further, the red message is unlikely to be a hardware bug because all devices
seem to work to the extent of my knowledge with Windows (the printer and the
scanner seem to work once you awake them from sleep or turn them on). The fact
that certain devices are connected to the computer and are simply asleep does
not constitute a user error warranting red color but is normal behavior and
should be treated as such. Of course, also the permissions of the USB device in
the file system might be wrong for whatever reason. So I speculate that the red
message is likely to be a software issue. I can't speculate further without
better error reporting.
The package colord in version 1.4.6-2.2 is installed.
Nitpickung for hard-boiled purists: `if (hd == NULL)` in musb.c should be
simplified to `if(!hd)`. 😊
Gratefully,
AlMa
Jul 30 23:39:45 AnonymizedMachineName dbus-daemon[871]: [system] Activating via
systemd: service name='org.freedesktop.ColorManager' unit='colord.service'
requested by ':1.14' (uid=0 pid=957 comm="/usr/sbin/cupsd -l")
Jul 30 23:39:45 AnonymizedMachineName sshd[975]: Server listening on 0.0.0.0
port 22.
Jul 30 23:39:45 AnonymizedMachineName sshd[975]: Server listening on :: port 22.
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Starting colord.service -
Manage, Install and Generate Color Profiles...
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started ssh.service - OpenBSD
Secure Shell server.
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started ModemManager.service
- Modem Manager.
Jul 30 23:39:45 AnonymizedMachineName kernel: NET: Registered PF_QIPCRTR
protocol family
Jul 30 23:39:45 AnonymizedMachineName udev-configure-printer[869]: device
devpath is /devices/pci0000:00/0000:00:14.0/usb1/1-11
Jul 30 23:39:45 AnonymizedMachineName udev-configure-printer[869]: MFG:hp
MDL:deskjet 5600 SERN:AnonymizedSerialNumber serial:AnonymizedSerialNumber
Jul 30 23:39:45 AnonymizedMachineName colord[976]: failed to get edid data:
EDID length is too small
Jul 30 23:39:45 AnonymizedMachineName dbus-daemon[871]: [system] Successfully
activated service 'org.freedesktop.ColorManager'
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started colord.service -
Manage, Install and Generate Color Profiles.
Jul 30 23:39:45 AnonymizedMachineName dbus-daemon[871]: [system] Successfully
activated service 'org.freedesktop.hostname1'
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started
systemd-hostnamed.service - Hostname Service.
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5362] hostname: hostname: using hostnamed
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5363] hostname: static hostname changed from (none) to
"AnonymizedMachineName"
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5365] dns-mgr: init: dns=default,systemd-resolved
rc-manager=symlink (auto)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5370] rfkill0: found Wi-Fi radio killswitch (at
/sys/devices/pci0000:b2/0000:b2:00.0/0000:b3:00.0/ieee80211/phy0/rfkill0)
(driver iwlwifi)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5371] manager[0x5591bef93000]: rfkill: Wi-Fi hardware radio set
enabled
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5371] manager[0x5591bef93000]: rfkill: WWAN hardware radio set
enabled
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5405] Loaded device plugin: NMWwanFactory
(/usr/lib/x86_64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-wwan.so)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5420] Loaded device plugin: NMTeamFactory
(/usr/lib/x86_64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-team.so)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5427] Loaded device plugin: NMWifiFactory
(/usr/lib/x86_64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-wifi.so)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5437] Loaded device plugin: NMBluezManager
(/usr/lib/x86_64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-bluetooth.so)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5441] Loaded device plugin: NMAtmManager
(/usr/lib/x86_64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-adsl.so)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5443] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled
by state file
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5443] manager: rfkill: WWAN enabled by radio killswitch; enabled by
state file
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5444] manager: Networking is enabled by state file
Jul 30 23:39:45 AnonymizedMachineName dbus-daemon[871]: [system] Activating via
systemd: service name='org.freedesktop.nm_dispatcher'
unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0
pid=901 comm="/usr/sbin/NetworkManager --no-daemon")
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5450] settings: Loaded settings plugin: ifupdown
("/usr/lib/x86_64-linux-gnu/NetworkManager/1.42.4/libnm-settings-plugin-ifupdown.so")
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5450] settings: Loaded settings plugin: keyfile (internal)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5450] ifupdown: management mode: unmanaged
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5450] ifupdown: interface-parser: parsing file
/etc/network/interfaces
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5450] ifupdown: interface-parser: source line includes
interfaces file(s) /etc/network/interfaces.d/*
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5451] ifupdown: interface-parser: parsing file
/etc/network/interfaces.d/setup
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5451] ifupdown: interface-parser: finished parsing file
/etc/network/interfaces.d/setup
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5451] ifupdown: interface-parser: finished parsing file
/etc/network/interfaces
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5454] ifupdown: guessed connection type (enp6s0) = 802-3-ethernet
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5458] ifupdown: guessed connection type (enp7s0) = 802-3-ethernet
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Starting
NetworkManager-dispatcher.service - Network Manager Script Dispatcher Service...
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started gdm.service - GNOME
Display Manager.
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5494] dhcp: init: Using DHCP client 'internal'
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5497] manager: (lo): new Loopback device
(/org/freedesktop/NetworkManager/Devices/1)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5505] device (lo): state change: unmanaged -> unavailable (reason
'connection-assumed', sys-iface-state: 'external')
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5508] device (lo): state change: unavailable -> disconnected
(reason 'connection-assumed', sys-iface-state: 'external')
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5513] device (lo): Activation: starting connection 'lo'
(AnonymizedUUID)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5521] manager: (enp6s0): new Ethernet device
(/org/freedesktop/NetworkManager/Devices/2)
Jul 30 23:39:45 AnonymizedMachineName dbus-daemon[871]: [system] Successfully
activated service 'org.freedesktop.nm_dispatcher'
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started
NetworkManager-dispatcher.service - Network Manager Script Dispatcher Service.
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5532] manager: (enp7s0): new Ethernet device
(/org/freedesktop/NetworkManager/Devices/3)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5537] device (wlp179s0): driver supports Access Point (AP) mode
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5542] manager: (wlp179s0): new 802.11 Wi-Fi device
(/org/freedesktop/NetworkManager/Devices/4)
Jul 30 23:39:45 AnonymizedMachineName NetworkManager[901]: <info>
[1690753185.5544] device (wlp179s0): state change: unmanaged -> unavailable
(reason 'managed', sys-iface-state: 'external')
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Received SIGRTMIN+21 from PID
307 (plymouthd).
Jul 30 23:39:45 AnonymizedMachineName gdm-launch-environment][992]:
pam_unix(gdm-launch-environment:session): session opened for user
Debian-gdm(uid=119) by (uid=0)
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Created slice user-119.slice
- User Slice of UID 119.
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Starting
user-runtime-dir@119.service - User Runtime Directory /run/user/119...
Jul 30 23:39:45 AnonymizedMachineName systemd-logind[886]: New session c1 of
user Debian-gdm.
Jul 30 23:39:45 AnonymizedMachineName acpid[865]: starting up with netlink and
the input layer
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Finished
user-runtime-dir@119.service - User Runtime Directory /run/user/119.
Jul 30 23:39:45 AnonymizedMachineName acpid[865]: 27 rules loaded
Jul 30 23:39:45 AnonymizedMachineName acpid[865]: waiting for events: event
logging is off
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Starting user@119.service -
User Manager for UID 119...
Jul 30 23:39:45 AnonymizedMachineName (systemd)[1006]:
pam_unix(systemd-user:session): session opened for user Debian-gdm(uid=119) by
(uid=0)
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started udisks2.service -
Disk Manager.
Jul 30 23:39:45 AnonymizedMachineName audit[957]: AVC apparmor="DENIED"
operation="capable" profile="/usr/sbin/cupsd" pid=957 comm="cupsd"
capability=12 capname="net_admin"
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Started cups.service - CUPS
Scheduler.
Jul 30 23:39:45 AnonymizedMachineName systemd[1]: Reached target printer.target
- Printer Support.
Jul 30 23:39:45 AnonymizedMachineName colord-sane[1000]: io/hpmud/musb.c 2101:
Invalid usb_open: Permission denied
