Logitech mouse G900 works only after ~6 seconds after plugging it in

Logitech mouse G900 works only after ~6 seconds after plugging it in

[Fabrizio Pelosi]

I'm having this issue since some kernel releases but I never took the time to report it since it's not so severe...
Anyway, if I plug my mouse (a Logitech G900) the device gets detected immediately but starts to work only after ~6 seconds as you can see from the log.

The mouse is connected via USB cable (without the wireless receiver).

I have this issue on a ArchLinux box (running kernel 5.3.8-arch1) and also on a Fedora 31 box.

NOTE: Also reported here: https://bugzilla.kernel.org/show_bug.cgi?id=205449

LOG:
nov 06 08:29:50 tesx-pc kernel: mousedev: PS/2 mouse device common for all mice
nov 06 08:29:50 tesx-pc kernel: logitech-hidpp-device 0003:046D:C081.0007: Device not connected
nov 06 08:29:45 tesx-pc kernel: logitech-hidpp-device 0003:046D:C081.0006: input,hidraw5: USB HID v1.11 Mouse [Logitech Gaming Mouse G900] on usb-0000:0f:00.3-1.2/input0
nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-1.2:1.0/0003:046D:C081.0006/input/input36
nov 06 08:29:45 tesx-pc kernel: hid-generic 0003:046D:C081.0007: input,hiddev4,hidraw6: USB HID v1.11 Keyboard [Logitech Gaming Mouse G900] on usb-0000:0f:00.3-1.2/input1
nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 System Control as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-1.2:1.1/0003:046D:C081.0007/input/input33
nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 Consumer Control as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-1.2:1.1/0003:046D:C081.0007/input/input32
nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 Keyboard as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-1.2:1.1/0003:046D:C081.0007/input/input31
nov 06 08:29:45 tesx-pc kernel: hid-generic 0003:046D:C081.0006: input,hidraw5: USB HID v1.11 Mouse [Logitech Gaming Mouse G900] on usb-0000:0f:00.3-1.2/input0
nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-1.2:1.0/0003:046D:C081.0006/input/input30
nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: SerialNumber: 0E6C36503233
nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: Manufacturer: Logitech
nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: Product: Gaming Mouse G900
nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: New USB device found, idVendor=046d, idProduct=c081, bcdDevice= 1.03
nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: new full-speed USB device number 5 using xhci_hcd

Re: Logitech mouse G900 works only after ~6 seconds after plugging it in

[Filipe Laíns]

[-- Attachment #1: Type: text/plain, Size: 2988 bytes --]

On Wed, 2019-11-06 at 13:42 +0100, Fabrizio Pelosi wrote:
> I'm having this issue since some kernel releases but I never took the
> time to report it since it's not so severe...
> Anyway, if I plug my mouse (a Logitech G900) the device gets detected
> immediately but starts to work only after ~6 seconds as you can see
> from the log.
> 
> The mouse is connected via USB cable (without the wireless receiver).
> 
> I have this issue on a ArchLinux box (running kernel 5.3.8-arch1) and
> also on a Fedora 31 box.
> 
> NOTE: Also reported here: 
> https://bugzilla.kernel.org/show_bug.cgi?id=205449
> 
> LOG:
> nov 06 08:29:50 tesx-pc kernel: mousedev: PS/2 mouse device common
> for all mice
> nov 06 08:29:50 tesx-pc kernel: logitech-hidpp-device
> 0003:046D:C081.0007: Device not connected
> nov 06 08:29:45 tesx-pc kernel: logitech-hidpp-device
> 0003:046D:C081.0006: input,hidraw5: USB HID v1.11 Mouse [Logitech
> Gaming Mouse G900] on usb-0000:0f:00.3-1.2/input0
> nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 as
> /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> 1.2:1.0/0003:046D:C081.0006/input/input36
> nov 06 08:29:45 tesx-pc kernel: hid-generic 0003:046D:C081.0007:
> input,hiddev4,hidraw6: USB HID v1.11 Keyboard [Logitech Gaming Mouse
> G900] on usb-0000:0f:00.3-1.2/input1
> nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900
> System Control as
> /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> 1.2:1.1/0003:046D:C081.0007/input/input33
> nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900
> Consumer Control as
> /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> 1.2:1.1/0003:046D:C081.0007/input/input32
> nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900
> Keyboard as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-
> 1.2/5-1.2:1.1/0003:046D:C081.0007/input/input31
> nov 06 08:29:45 tesx-pc kernel: hid-generic 0003:046D:C081.0006:
> input,hidraw5: USB HID v1.11 Mouse [Logitech Gaming Mouse G900] on
> usb-0000:0f:00.3-1.2/input0
> nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 as
> /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> 1.2:1.0/0003:046D:C081.0006/input/input30
> nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: SerialNumber: 0E6C36503233
> nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: Manufacturer: Logitech
> nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: Product: Gaming Mouse G900
> nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: New USB device strings:
> Mfr=1, Product=2, SerialNumber=3
> nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: New USB device found,
> idVendor=046d, idProduct=c081, bcdDevice= 1.03
> nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: new full-speed USB device
> number 5 using xhci_hcd

Oh, it looks to me like the driver thinks the usb device it's a
receiver. I will check this out when I get home.

Cheers,
Filipe Laíns

[-- Attachment #2: This is a digitally signed message part --]
[-- Type: application/pgp-signature, Size: 833 bytes --]

Re: Logitech mouse G900 works only after ~6 seconds after plugging it in

[Fabrizio Pelosi]

I confirm this bug is still present as of kernel 6.3.3-arch1-1.

I've tried running `udevadm monitor` and then switching the mouse on
and this was the output.
Notice that there is a jump between 204 seconds of uptime to 219
seconds after which the mouse starts effectively working.

BTW, for those who don't know, the mouse is a hybrid wireless / wired
one and I keep it always plugged in but with the switch underneath it
on OFF.
Only with the switch ON the mouse works (even wired), otherwise the
mouse charges up its battery.

$ udevadm monitor

monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent

KERNEL[204.392972] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3 (usb)
KERNEL[204.459877] change
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3 (usb)
KERNEL[204.462886] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0
(usb)
KERNEL[204.469946] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009
(hid)
KERNEL[204.469977] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/input/input33
(input)
KERNEL[204.470004] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/input/input33/event2
(input)
KERNEL[204.470096] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/input/input33/mouse0
(input)
KERNEL[204.470160] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/hidraw/hidraw2
(hidraw)
KERNEL[204.470189] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009
(hid)
KERNEL[204.470210] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0
(usb)
KERNEL[204.470228] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1
(usb)
KERNEL[204.477003] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A
(hid)
KERNEL[204.477037] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34
(input)
KERNEL[204.533609] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34/event28
(input)
KERNEL[204.533634] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/usbmisc/hiddev2
(usbmisc)
KERNEL[204.533651] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/hidraw/hidraw3
(hidraw)
KERNEL[219.960876] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34/event28
(input)
KERNEL[219.987314] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34
(input)
KERNEL[219.987361] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/usbmisc/hiddev2
(usbmisc)
KERNEL[219.987376] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/hidraw/hidraw3
(hidraw)
KERNEL[219.987414] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1
(usb)
KERNEL[219.987446] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3 (usb)
UDEV  [220.004789] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3 (usb)
UDEV  [220.006104] change
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3 (usb)
UDEV  [220.006832] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0
(usb)
UDEV  [220.007558] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009
(hid)
UDEV  [220.008239] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1
(usb)
UDEV  [220.008651] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/input/input33
(input)
UDEV  [220.008953] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A
(hid)
UDEV  [220.010192] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34
(input)
UDEV  [220.010634] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/usbmisc/hiddev2
(usbmisc)
UDEV  [220.010975] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/input/input33/mouse0
(input)
UDEV  [220.011169] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/usbmisc/hiddev2
(usbmisc)
UDEV  [220.011451] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34/event28
(input)
UDEV  [220.012159] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34/event28
(input)
UDEV  [220.012513] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/hidraw/hidraw2
(hidraw)
UDEV  [220.012815] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/input/input34
(input)
UDEV  [220.013516] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/hidraw/hidraw3
(hidraw)
UDEV  [220.014110] remove
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1/0003:046D:C081.000A/hidraw/hidraw3
(hidraw)
UDEV  [220.014753] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.1
(usb)
UDEV  [220.132774] add
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009/input/input33/event2
(input)
UDEV  [220.133439] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0/0003:046D:C081.0009
(hid)
UDEV  [220.134102] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3/7-1.3:1.0
(usb)
UDEV  [220.136452] bind
/devices/pci0000:00/0000:00:08.1/0000:0e:00.3/usb7/7-1/7-1.3 (usb)


Il giorno mer 6 nov 2019 alle ore 14:01 Filipe Laíns
<lains@archlinux.org> ha scritto:
>
> On Wed, 2019-11-06 at 13:42 +0100, Fabrizio Pelosi wrote:
> > I'm having this issue since some kernel releases but I never took the
> > time to report it since it's not so severe...
> > Anyway, if I plug my mouse (a Logitech G900) the device gets detected
> > immediately but starts to work only after ~6 seconds as you can see
> > from the log.
> >
> > The mouse is connected via USB cable (without the wireless receiver).
> >
> > I have this issue on a ArchLinux box (running kernel 5.3.8-arch1) and
> > also on a Fedora 31 box.
> >
> > NOTE: Also reported here:
> > https://bugzilla.kernel.org/show_bug.cgi?id=205449
> >
> > LOG:
> > nov 06 08:29:50 tesx-pc kernel: mousedev: PS/2 mouse device common
> > for all mice
> > nov 06 08:29:50 tesx-pc kernel: logitech-hidpp-device
> > 0003:046D:C081.0007: Device not connected
> > nov 06 08:29:45 tesx-pc kernel: logitech-hidpp-device
> > 0003:046D:C081.0006: input,hidraw5: USB HID v1.11 Mouse [Logitech
> > Gaming Mouse G900] on usb-0000:0f:00.3-1.2/input0
> > nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 as
> > /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> > 1.2:1.0/0003:046D:C081.0006/input/input36
> > nov 06 08:29:45 tesx-pc kernel: hid-generic 0003:046D:C081.0007:
> > input,hiddev4,hidraw6: USB HID v1.11 Keyboard [Logitech Gaming Mouse
> > G900] on usb-0000:0f:00.3-1.2/input1
> > nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900
> > System Control as
> > /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> > 1.2:1.1/0003:046D:C081.0007/input/input33
> > nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900
> > Consumer Control as
> > /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> > 1.2:1.1/0003:046D:C081.0007/input/input32
> > nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900
> > Keyboard as /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-
> > 1.2/5-1.2:1.1/0003:046D:C081.0007/input/input31
> > nov 06 08:29:45 tesx-pc kernel: hid-generic 0003:046D:C081.0006:
> > input,hidraw5: USB HID v1.11 Mouse [Logitech Gaming Mouse G900] on
> > usb-0000:0f:00.3-1.2/input0
> > nov 06 08:29:45 tesx-pc kernel: input: Logitech Gaming Mouse G900 as
> > /devices/pci0000:00/0000:00:08.1/0000:0f:00.3/usb5/5-1/5-1.2/5-
> > 1.2:1.0/0003:046D:C081.0006/input/input30
> > nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: SerialNumber: 0E6C36503233
> > nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: Manufacturer: Logitech
> > nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: Product: Gaming Mouse G900
> > nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: New USB device strings:
> > Mfr=1, Product=2, SerialNumber=3
> > nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: New USB device found,
> > idVendor=046d, idProduct=c081, bcdDevice= 1.03
> > nov 06 08:29:44 tesx-pc kernel: usb 5-1.2: new full-speed USB device
> > number 5 using xhci_hcd
>
> Oh, it looks to me like the driver thinks the usb device it's a
> receiver. I will check this out when I get home.
>
> Cheers,
> Filipe Laíns