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