Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

rpi3 kernel: [61372.274158] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on #1804

Open
iz8mbw opened this issue Apr 3, 2023 · 7 comments
Open

rpi3 kernel: [61372.274158] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on #1804

iz8mbw opened this issue Apr 3, 2023 · 7 comments

Comments

@iz8mbw
Copy link

iz8mbw commented Apr 3, 2023 •
edited
Loading

Hello.
Using a Raspberry Pi 3 Model B+ with latest Raspberry Pi OS (64-bit):

Linux rpi3 6.1.19-v8+ #1637 SMP PREEMPT Tue Mar 14 11:11:47 GMT 2023 aarch64 GNU/Linux

root@rpi3:~# cat /etc/debian_version
11.6

On one USB port I have a USB to RS485 adapter (using PL2303 chip):

Apr  2 15:16:43 rpi3 kernel: [   12.199081] usbcore: registered new interface driver usbserial_generic
Apr  2 15:16:43 rpi3 kernel: [   12.200645] usbserial: USB Serial support registered for generic
Apr  2 15:16:43 rpi3 kernel: [   12.260137] usbcore: registered new interface driver pl2303
Apr  2 15:16:43 rpi3 kernel: [   12.273678] usbserial: USB Serial support registered for pl2303
Apr  2 15:16:43 rpi3 kernel: [   12.282038] pl2303 1-1.1.3:1.0: pl2303 converter detected
Apr  2 15:16:43 rpi3 kernel: [   12.317290] usb 1-1.1.3: pl2303 converter now attached to ttyUSB0

root@rpi3:~# lsusb -v

Bus 001 Device 004: ID 0930:6544 Toshiba Corp. TransMemory-Mini / Kingston DataTraveler 2.0 Stick
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  idVendor           0x0930 Toshiba Corp.
  idProduct          0x6544 TransMemory-Mini / Kingston DataTraveler 2.0 Stick
  bcdDevice            1.00
  iManufacturer           1 Kingston
  iProduct                2 DataTraveler 2.0
  iSerial                 3 0019B92B28C9C8C135450044
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0020
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass         8 Mass Storage
      bInterfaceSubClass      6 SCSI
      bInterfaceProtocol     80 Bulk-Only
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval             255
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval             255
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            0
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  bNumConfigurations      1
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0000
  (Bus Powered)

Bus 001 Device 005: ID 067b:2303 Prolific Technology, Inc. PL2303 Serial Port / Mobile Action MA-8910P
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               1.10
  bDeviceClass            0
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0        64
  idVendor           0x067b Prolific Technology, Inc.
  idProduct          0x2303 PL2303 Serial Port / Mobile Action MA-8910P
  bcdDevice            3.00
  iManufacturer           1 Prolific Technology Inc.
  iProduct                2 USB-Serial Controller
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0027
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0
      bInterfaceProtocol      0
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x000a  1x 10 bytes
        bInterval               1
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0040  1x 64 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0040  1x 64 bytes
        bInterval               0
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0000
  (Bus Powered)

Bus 001 Device 006: ID 0424:7800 Microchip Technology, Inc. (formerly SMSC)
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.10
  bDeviceClass          255 Vendor Specific Class
  bDeviceSubClass         0
  bDeviceProtocol       255
  bMaxPacketSize0        64
  idVendor           0x0424 Microchip Technology, Inc. (formerly SMSC)
  idProduct          0x7800
  bcdDevice            3.00
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0027
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0
      bInterfaceProtocol    255
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0010  1x 16 bytes
        bInterval               4
Binary Object Store Descriptor:
  bLength                 5
  bDescriptorType        15
  wTotalLength       0x0016
  bNumDeviceCaps          2
  USB 2.0 Extension Device Capability:
    bLength                 7
    bDescriptorType        16
    bDevCapabilityType      2
    bmAttributes   0x00000006
      BESL Link Power Management (LPM) Supported
  SuperSpeed USB Device Capability:
    bLength                10
    bDescriptorType        16
    bDevCapabilityType      3
    bmAttributes         0x00
    wSpeedsSupported   0x000e
      Device can operate at Full Speed (12Mbps)
      Device can operate at High Speed (480Mbps)
      Device can operate at SuperSpeed (5Gbps)
    bFunctionalitySupport   1
      Lowest fully-functional device speed is Full Speed (12Mbps)
    bU1DevExitLat          10 micro seconds
    bU2DevExitLat        1500 micro seconds
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0001
  Self Powered

Bus 001 Device 003: ID 0424:2514 Microchip Technology, Inc. (formerly SMSC) USB 2.0 Hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         2 TT per port
  bMaxPacketSize0        64
  idVendor           0x0424 Microchip Technology, Inc. (formerly SMSC)
  idProduct          0x2514 USB 2.0 Hub
  bcdDevice            b.b3
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0029
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      1 Single TT
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      2 TT per port
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             3
  wHubCharacteristic 0x000d
    Per-port power switching
    Compound device
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood       50 * 2 milli seconds
  bHubContrCurrent      1 milli Ampere
  DeviceRemovable    0x02
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0503 highspeed power enable connect
   Port 2: 0000.0100 power
   Port 3: 0000.0103 power enable connect
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         0 Full speed (or root) hub
  bMaxPacketSize0        64
  bNumConfigurations      1
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0001
  Self Powered

Bus 001 Device 002: ID 0424:2514 Microchip Technology, Inc. (formerly SMSC) USB 2.0 Hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         2 TT per port
  bMaxPacketSize0        64
  idVendor           0x0424 Microchip Technology, Inc. (formerly SMSC)
  idProduct          0x2514 USB 2.0 Hub
  bcdDevice            b.b3
  iManufacturer           0
  iProduct                0
  iSerial                 0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0029
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                2mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      1 Single TT
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      2 TT per port
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0001  1x 1 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             4
  wHubCharacteristic 0x000d
    Per-port power switching
    Compound device
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood       50 * 2 milli seconds
  bHubContrCurrent      1 milli Ampere
  DeviceRemovable    0x02
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0503 highspeed power enable connect
   Port 2: 0000.0100 power
   Port 3: 0000.0503 highspeed power enable connect
   Port 4: 0000.0100 power
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         0 Full speed (or root) hub
  bMaxPacketSize0        64
  bNumConfigurations      1
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0001
  Self Powered

Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0002 2.0 root hub
  bcdDevice            6.01
  iManufacturer           3 Linux 6.1.19-v8+ dwc_otg_hcd
  iProduct                2 DWC OTG Controller
  iSerial                 1 3f980000.usb
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0019
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             1
  wHubCharacteristic 0x0008
    Ganged power switching
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood        1 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0503 highspeed power enable connect
can't get device qualifier: Resource temporarily unavailable
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0001
  Self Powered

On this RS485 interface /dev/ttyUSB0 I poll every 2 seconds a SDM120 Smart Meter (Modbus) with this software.
All works well except I have lots of WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on in /var/log/messages.

See here an extract:


Apr  3 07:07:43 rpi3 kernel: [55406.970480] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:08:05 rpi3 kernel: [55428.794721]
Apr  3 07:08:05 rpi3 kernel: [55428.794737] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:08:21 rpi3 kernel: [55445.146825]
Apr  3 07:08:21 rpi3 kernel: [55445.146841] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:08:21 rpi3 kernel: [55445.147251]
Apr  3 07:08:21 rpi3 kernel: [55445.147254] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:09:10 rpi3 kernel: [55493.851483]
Apr  3 07:09:10 rpi3 kernel: [55493.851499] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:09:27 rpi3 kernel: [55511.035465]
Apr  3 07:09:27 rpi3 kernel: [55511.035481] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:09:37 rpi3 kernel: [55520.763577]
Apr  3 07:09:37 rpi3 kernel: [55520.763593] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:09:37 rpi3 kernel: [55520.764016]
Apr  3 07:09:37 rpi3 kernel: [55520.764019] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:09:44 rpi3 kernel: [55528.059855]
Apr  3 07:09:44 rpi3 kernel: [55528.059872] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:10:35 rpi3 kernel: [55578.844230]
Apr  3 07:10:35 rpi3 kernel: [55578.844246] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:10:48 rpi3 kernel: [55591.804200]
Apr  3 07:10:48 rpi3 kernel: [55591.804216] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:10:48 rpi3 kernel: [55591.804636]
Apr  3 07:10:48 rpi3 kernel: [55591.804640] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:11:46 rpi3 kernel: [55649.692970]
Apr  3 07:11:46 rpi3 kernel: [55649.692986] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:11:57 rpi3 kernel: [55661.533102]
Apr  3 07:11:57 rpi3 kernel: [55661.533119] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:13:46 rpi3 kernel: [55770.109949]
Apr  3 07:13:46 rpi3 kernel: [55770.109966] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:13:53 rpi3 kernel: [55777.405957]
Apr  3 07:13:53 rpi3 kernel: [55777.405973] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:13:53 rpi3 kernel: [55777.406395]
Apr  3 07:13:53 rpi3 kernel: [55777.406398] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:14:46 rpi3 kernel: [55830.334463]
Apr  3 07:14:46 rpi3 kernel: [55830.334479] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:15:33 rpi3 kernel: [55876.926970]
Apr  3 07:15:33 rpi3 kernel: [55876.926986] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:16:39 rpi3 kernel: [55943.327586]
Apr  3 07:16:39 rpi3 kernel: [55943.327602] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:16:39 rpi3 kernel: [55943.328032]
Apr  3 07:16:39 rpi3 kernel: [55943.328035] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:16:56 rpi3 kernel: [55959.903701]
Apr  3 07:16:56 rpi3 kernel: [55959.903717] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:16:56 rpi3 kernel: [55959.904135]
Apr  3 07:16:56 rpi3 kernel: [55959.904138] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:17:08 rpi3 kernel: [55971.903972]
Apr  3 07:17:08 rpi3 kernel: [55971.903987] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:17:27 rpi3 kernel: [55991.487949]
Apr  3 07:17:27 rpi3 kernel: [55991.487965] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:17:27 rpi3 kernel: [55991.488389]
Apr  3 07:17:27 rpi3 kernel: [55991.488393] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:17:35 rpi3 kernel: [55998.720229]
Apr  3 07:17:35 rpi3 kernel: [55998.720245] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:18:26 rpi3 kernel: [56049.728598]
Apr  3 07:18:26 rpi3 kernel: [56049.728615] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:20:31 rpi3 kernel: [56174.945843]
Apr  3 07:20:31 rpi3 kernel: [56174.945859] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:21:15 rpi3 kernel: [56218.658224]
Apr  3 07:21:15 rpi3 kernel: [56218.658241] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:21:22 rpi3 kernel: [56226.434080]
Apr  3 07:21:22 rpi3 kernel: [56226.434097] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:22:05 rpi3 kernel: [56268.610913]
Apr  3 07:22:05 rpi3 kernel: [56268.610931] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:22:48 rpi3 kernel: [56311.746977]
Apr  3 07:22:48 rpi3 kernel: [56311.746994] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:23:13 rpi3 kernel: [56336.707222]
Apr  3 07:23:13 rpi3 kernel: [56336.707237] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:25:18 rpi3 kernel: [56462.468340]
Apr  3 07:25:18 rpi3 kernel: [56462.468356] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:26:01 rpi3 kernel: [56505.316998]
Apr  3 07:26:01 rpi3 kernel: [56505.317022] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:26:23 rpi3 kernel: [56527.108953]
Apr  3 07:26:23 rpi3 kernel: [56527.108969] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:27:08 rpi3 kernel: [56572.421335]
Apr  3 07:27:08 rpi3 kernel: [56572.421351] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:27:42 rpi3 kernel: [56605.893864]
Apr  3 07:27:42 rpi3 kernel: [56605.893881] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:29:14 rpi3 kernel: [56697.638604]
Apr  3 07:29:14 rpi3 kernel: [56697.638621] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:30:01 rpi3 kernel: [56744.743121]
Apr  3 07:30:01 rpi3 kernel: [56744.743140] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:30:17 rpi3 kernel: [56761.191232]
Apr  3 07:30:17 rpi3 kernel: [56761.191249] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:30:39 rpi3 kernel: [56782.759468]
Apr  3 07:30:39 rpi3 kernel: [56782.759485] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:30:58 rpi3 kernel: [56802.535598]
Apr  3 07:30:58 rpi3 kernel: [56802.535615] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:32:34 rpi3 kernel: [56898.536457]
Apr  3 07:32:34 rpi3 kernel: [56898.536473] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:35:37 rpi3 kernel: [57081.001961]
Apr  3 07:35:37 rpi3 kernel: [57081.001977] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:36:50 rpi3 kernel: [57154.378707]
Apr  3 07:36:50 rpi3 kernel: [57154.378723] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:36:50 rpi3 kernel: [57154.379148]
Apr  3 07:36:50 rpi3 kernel: [57154.379151] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:36:57 rpi3 kernel: [57161.546974]
Apr  3 07:36:57 rpi3 kernel: [57161.546991] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:39:04 rpi3 kernel: [57287.595958]
Apr  3 07:39:04 rpi3 kernel: [57287.595974] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:40:13 rpi3 kernel: [57357.388579]
Apr  3 07:40:13 rpi3 kernel: [57357.388595] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:40:13 rpi3 kernel: [57357.389015]
Apr  3 07:40:13 rpi3 kernel: [57357.389018] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:42:00 rpi3 kernel: [57463.789588]
Apr  3 07:42:00 rpi3 kernel: [57463.789604] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:42:00 rpi3 kernel: [57463.790029]
Apr  3 07:42:00 rpi3 kernel: [57463.790032] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:42:16 rpi3 kernel: [57480.429714]
Apr  3 07:42:16 rpi3 kernel: [57480.429731] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:42:26 rpi3 kernel: [57489.773836]
Apr  3 07:42:26 rpi3 kernel: [57489.773853] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:42:26 rpi3 kernel: [57489.774268]
Apr  3 07:42:26 rpi3 kernel: [57489.774272] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:42:54 rpi3 kernel: [57518.510089]
Apr  3 07:42:54 rpi3 kernel: [57518.510105] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:43:04 rpi3 kernel: [57528.494227]
Apr  3 07:43:04 rpi3 kernel: [57528.494243] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:44:42 rpi3 kernel: [57625.679098]
Apr  3 07:44:42 rpi3 kernel: [57625.679114] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:45:59 rpi3 kernel: [57702.703707]
Apr  3 07:45:59 rpi3 kernel: [57702.703723] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:45:59 rpi3 kernel: [57702.704141]
Apr  3 07:45:59 rpi3 kernel: [57702.704144] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:46:44 rpi3 kernel: [57747.984347]
Apr  3 07:46:44 rpi3 kernel: [57747.984363] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:47:52 rpi3 kernel: [57816.432847]
Apr  3 07:47:52 rpi3 kernel: [57816.432863] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:48:17 rpi3 kernel: [57840.721232]
Apr  3 07:48:17 rpi3 kernel: [57840.721248] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:49:25 rpi3 kernel: [57908.977716]
Apr  3 07:49:25 rpi3 kernel: [57908.977733] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:49:25 rpi3 kernel: [57908.978162]
Apr  3 07:49:25 rpi3 kernel: [57908.978166] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:49:28 rpi3 kernel: [57911.665849]
Apr  3 07:49:28 rpi3 kernel: [57911.665865] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:50:16 rpi3 kernel: [57959.634229]
Apr  3 07:50:16 rpi3 kernel: [57959.634246] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:50:54 rpi3 kernel: [57998.418581]
Apr  3 07:50:54 rpi3 kernel: [57998.418597] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:50:54 rpi3 kernel: [57998.419019]
Apr  3 07:50:54 rpi3 kernel: [57998.419022] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:51:23 rpi3 kernel: [58027.251109]
Apr  3 07:51:23 rpi3 kernel: [58027.251126] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:52:30 rpi3 kernel: [58093.747342]
Apr  3 07:52:30 rpi3 kernel: [58093.747358] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:53:04 rpi3 kernel: [58127.987715]
Apr  3 07:53:04 rpi3 kernel: [58127.987731] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 07:53:22 rpi3 kernel: [58146.355963]
Apr  3 07:53:22 rpi3 kernel: [58146.355979] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 07:53:22 rpi3 kernel: [58146.356409]
Apr  3 07:53:22 rpi3 kernel: [58146.356412] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:53:51 rpi3 kernel: [58175.380220]
Apr  3 07:53:51 rpi3 kernel: [58175.380236] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 07:53:51 rpi3 kernel: [58175.380664]
Apr  3 07:53:51 rpi3 kernel: [58175.380668] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:54:20 rpi3 kernel: [58204.436476]
Apr  3 07:54:20 rpi3 kernel: [58204.436492] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:55:09 rpi3 kernel: [58253.461112]
Apr  3 07:55:09 rpi3 kernel: [58253.461128] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:55:12 rpi3 kernel: [58256.052830]
Apr  3 07:55:12 rpi3 kernel: [58256.052846] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:55:12 rpi3 kernel: [58256.053254]
Apr  3 07:55:12 rpi3 kernel: [58256.053257] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:55:49 rpi3 kernel: [58292.597223]
Apr  3 07:55:49 rpi3 kernel: [58292.597240] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:56:01 rpi3 kernel: [58304.949479]
Apr  3 07:56:01 rpi3 kernel: [58304.949496] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:56:42 rpi3 kernel: [58345.973850]
Apr  3 07:56:42 rpi3 kernel: [58345.973867] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 07:57:17 rpi3 kernel: [58381.462083]
Apr  3 07:57:17 rpi3 kernel: [58381.462099] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 07:57:59 rpi3 kernel: [58422.902479]
Apr  3 07:57:59 rpi3 kernel: [58422.902494] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:58:37 rpi3 kernel: [58461.334976]
Apr  3 07:58:37 rpi3 kernel: [58461.334992] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:58:42 rpi3 kernel: [58465.910842]
Apr  3 07:58:42 rpi3 kernel: [58465.910858] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 07:59:11 rpi3 kernel: [58495.127083]
Apr  3 07:59:11 rpi3 kernel: [58495.127099] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 07:59:11 rpi3 kernel: [58495.127517]
Apr  3 07:59:11 rpi3 kernel: [58495.127521] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 07:59:25 rpi3 kernel: [58509.239226]
Apr  3 07:59:25 rpi3 kernel: [58509.239242] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:01:00 rpi3 kernel: [58604.440363]
Apr  3 08:01:00 rpi3 kernel: [58604.440380] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:01:32 rpi3 kernel: [58635.640341]
Apr  3 08:01:32 rpi3 kernel: [58635.640357] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:01:53 rpi3 kernel: [58656.824594]
Apr  3 08:01:53 rpi3 kernel: [58656.824609] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:02:36 rpi3 kernel: [58700.313254]
Apr  3 08:02:36 rpi3 kernel: [58700.313271] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 08:02:53 rpi3 kernel: [58717.369093]
Apr  3 08:02:53 rpi3 kernel: [58717.369109] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:03:23 rpi3 kernel: [58746.713601]
Apr  3 08:03:23 rpi3 kernel: [58746.713617] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 08:03:32 rpi3 kernel: [58756.057473]
Apr  3 08:03:32 rpi3 kernel: [58756.057490] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:03:58 rpi3 kernel: [58781.849853]
Apr  3 08:03:58 rpi3 kernel: [58781.849869] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 08:04:13 rpi3 kernel: [58796.921985]
Apr  3 08:04:13 rpi3 kernel: [58796.922002] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:04:57 rpi3 kernel: [58841.050343]
Apr  3 08:04:57 rpi3 kernel: [58841.050359] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:05:16 rpi3 kernel: [58860.250468]
Apr  3 08:05:16 rpi3 kernel: [58860.250484] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:06:02 rpi3 kernel: [58905.946854]
Apr  3 08:06:02 rpi3 kernel: [58905.946871] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:06:18 rpi3 kernel: [58922.459237]
Apr  3 08:06:18 rpi3 kernel: [58922.459254] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:06:57 rpi3 kernel: [58961.371465]
Apr  3 08:06:57 rpi3 kernel: [58961.371481] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:07:10 rpi3 kernel: [58973.723459]
Apr  3 08:07:10 rpi3 kernel: [58973.723476] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:07:10 rpi3 kernel: [58973.723900]
Apr  3 08:07:10 rpi3 kernel: [58973.723903] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:07:35 rpi3 kernel: [58998.715713]
Apr  3 08:07:35 rpi3 kernel: [58998.715730] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:07:35 rpi3 kernel: [58998.716150]
Apr  3 08:07:35 rpi3 kernel: [58998.716154] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:08:15 rpi3 kernel: [59039.388085]
Apr  3 08:08:15 rpi3 kernel: [59039.388100] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:08:15 rpi3 kernel: [59039.388516]
Apr  3 08:08:15 rpi3 kernel: [59039.388519] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:08:20 rpi3 kernel: [59044.572213]
Apr  3 08:08:20 rpi3 kernel: [59044.572229] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:08:20 rpi3 kernel: [59044.572648]
Apr  3 08:08:20 rpi3 kernel: [59044.572652] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:08:28 rpi3 kernel: [59051.836352]
Apr  3 08:08:28 rpi3 kernel: [59051.836368] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:09:14 rpi3 kernel: [59097.820723]
Apr  3 08:09:14 rpi3 kernel: [59097.820739] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:09:21 rpi3 kernel: [59104.956707]
Apr  3 08:09:21 rpi3 kernel: [59104.956723] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:09:21 rpi3 kernel: [59104.957140]
Apr  3 08:09:21 rpi3 kernel: [59104.957143] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:10:22 rpi3 kernel: [59165.949335]
Apr  3 08:10:22 rpi3 kernel: [59165.949351] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 08:13:18 rpi3 kernel: [59342.142968]
Apr  3 08:13:18 rpi3 kernel: [59342.142985] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:13:18 rpi3 kernel: [59342.143407]
Apr  3 08:13:18 rpi3 kernel: [59342.143410] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:13:28 rpi3 kernel: [59351.935108]
Apr  3 08:13:28 rpi3 kernel: [59351.935124] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 08:13:52 rpi3 kernel: [59375.999229]
Apr  3 08:13:52 rpi3 kernel: [59375.999244] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:13:56 rpi3 kernel: [59380.575493]
Apr  3 08:13:56 rpi3 kernel: [59380.575510] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:14:04 rpi3 kernel: [59388.383336]
Apr  3 08:14:04 rpi3 kernel: [59388.383352] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:14:12 rpi3 kernel: [59396.191473]
Apr  3 08:14:12 rpi3 kernel: [59396.191490] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:14:19 rpi3 kernel: [59403.327745]
Apr  3 08:14:19 rpi3 kernel: [59403.327762] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:15:29 rpi3 kernel: [59472.736102]
Apr  3 08:15:29 rpi3 kernel: [59472.736119] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:15:39 rpi3 kernel: [59483.104358]
Apr  3 08:15:39 rpi3 kernel: [59483.104374] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:16:11 rpi3 kernel: [59515.360586]
Apr  3 08:16:11 rpi3 kernel: [59515.360602] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:16:11 rpi3 kernel: [59515.361022]
Apr  3 08:16:11 rpi3 kernel: [59515.361025] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:16:26 rpi3 kernel: [59530.048597]
Apr  3 08:16:26 rpi3 kernel: [59530.048614] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:17:00 rpi3 kernel: [59563.713116]
Apr  3 08:17:00 rpi3 kernel: [59563.713133] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:17:29 rpi3 kernel: [59593.345215]
Apr  3 08:17:29 rpi3 kernel: [59593.345232] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:17:41 rpi3 kernel: [59605.217487]
Apr  3 08:17:41 rpi3 kernel: [59605.217503] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:19:03 rpi3 kernel: [59686.690250]
Apr  3 08:19:03 rpi3 kernel: [59686.690269] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 08:19:44 rpi3 kernel: [59728.130471]
Apr  3 08:19:44 rpi3 kernel: [59728.130487] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:19:49 rpi3 kernel: [59732.706598]
Apr  3 08:19:49 rpi3 kernel: [59732.706615] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:19:49 rpi3 kernel: [59732.707057]
Apr  3 08:19:49 rpi3 kernel: [59732.707061] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:20:15 rpi3 kernel: [59758.978863]
Apr  3 08:20:15 rpi3 kernel: [59758.978879] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:23:10 rpi3 kernel: [59934.308483]
Apr  3 08:23:10 rpi3 kernel: [59934.308499] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 08:28:54 rpi3 kernel: [60278.407724]
Apr  3 08:28:54 rpi3 kernel: [60278.407740] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:28:54 rpi3 kernel: [60278.408171]
Apr  3 08:28:54 rpi3 kernel: [60278.408174] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:28:59 rpi3 kernel: [60283.591590]
Apr  3 08:28:59 rpi3 kernel: [60283.591606] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:28:59 rpi3 kernel: [60283.592025]
Apr  3 08:28:59 rpi3 kernel: [60283.592028] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 4
Apr  3 08:30:00 rpi3 kernel: [60343.912218]
Apr  3 08:30:00 rpi3 kernel: [60343.912234] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:30:58 rpi3 kernel: [60402.472855]
Apr  3 08:30:58 rpi3 kernel: [60402.472871] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:32:13 rpi3 kernel: [60476.617472]
Apr  3 08:32:13 rpi3 kernel: [60476.617488] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 08:32:13 rpi3 kernel: [60476.617918]
Apr  3 08:32:13 rpi3 kernel: [60476.617921] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:33:18 rpi3 kernel: [60542.314112]
Apr  3 08:33:18 rpi3 kernel: [60542.314128] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:33:43 rpi3 kernel: [60566.634368]
Apr  3 08:33:43 rpi3 kernel: [60566.634385] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:35:00 rpi3 kernel: [60644.554966]
Apr  3 08:35:00 rpi3 kernel: [60644.554982] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 0
Apr  3 08:35:15 rpi3 kernel: [60659.595092]
Apr  3 08:35:15 rpi3 kernel: [60659.595108] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:35:23 rpi3 kernel: [60667.051101]
Apr  3 08:35:23 rpi3 kernel: [60667.051117] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:36:18 rpi3 kernel: [60722.155855]
Apr  3 08:36:18 rpi3 kernel: [60722.155871] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 08:36:45 rpi3 kernel: [60748.939968]
Apr  3 08:36:45 rpi3 kernel: [60748.939985] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:37:06 rpi3 kernel: [60770.060092]
Apr  3 08:37:06 rpi3 kernel: [60770.060108] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6
Apr  3 08:37:06 rpi3 kernel: [60770.060528]
Apr  3 08:37:06 rpi3 kernel: [60770.060531] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1
Apr  3 08:38:25 rpi3 kernel: [60848.748986]
Apr  3 08:38:25 rpi3 kernel: [60848.749003] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2
Apr  3 08:39:03 rpi3 kernel: [60887.181225]
Apr  3 08:39:03 rpi3 kernel: [60887.181240] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:39:39 rpi3 kernel: [60923.277746]
Apr  3 08:39:39 rpi3 kernel: [60923.277763] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:39:54 rpi3 kernel: [60937.773856]
Apr  3 08:39:54 rpi3 kernel: [60937.773873] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:39:54 rpi3 kernel: [60937.774306]
Apr  3 08:39:54 rpi3 kernel: [60937.774309] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 3
Apr  3 08:40:09 rpi3 kernel: [60952.845847]
Apr  3 08:40:09 rpi3 kernel: [60952.845863] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5
Apr  3 08:40:09 rpi3 kernel: [60952.846280]
Apr  3 08:40:09 rpi3 kernel: [60952.846284] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7
Apr  3 08:42:39 rpi3 kernel: [61103.375435]
Apr  3 08:42:39 rpi3 kernel: [61103.375459] WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 2

I have the same warning on messages also if I try to use another USB ot RS485 adapter using the "ch341" chip.

Thanks for your support.
@usaf-barnes
Copy link

I am also seeing this a lot on my installation.

RASPBERRY PI VERSION VERSION

Raspberry Pi 3B+

RETTY_NAME="Debian GNU/Linux 11 (bullseye)"
NAME="Debian GNU/Linux"
VERSION_ID="11"
VERSION="11 (bullseye)"
VERSION_CODENAME=bullseye
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"

Client: Docker Engine - Community
Version: 24.0.2
API version: 1.43
Go version: go1.20.4
Git commit: cb74dfc
Built: Thu May 25 21:50:59 2023
OS/Arch: linux/arm64
Context: default

DOCKER VERSION

Server: Docker Engine - Community
Engine:
Version: 24.0.2
API version: 1.43 (minimum version 1.12)
Go version: go1.20.4
Git commit: 659604f
Built: Thu May 25 21:50:59 2023
OS/Arch: linux/arm64
Experimental: false
containerd:
Version: 1.6.21
GitCommit: 3dce8eb055cbb6872793272b4f20ed16117344f8
runc:
Version: 1.1.7
GitCommit: v1.1.7-0-g860f061
docker-init:
Version: 0.19.0
GitCommit: de40ad0

@iz8mbw
Copy link
Author

iz8mbw commented Jun 9, 2023 •
edited
Loading

Hello.
For now I have changed my setup so instead to use https://github.com/gianfrdp/SDM120C software to poll my SDM120 meter, I'm using mbusd and "call" it via mbpoll command.
So the software that uses the serial port is mbusd and in this way I don't have any more Timed out waiting for FSM NP transfer to complete on the /var/log/messages.

@vrobert78
Copy link

I had the same issue with the 64bits edition of Raspberry Pi OS. I reinstalled the 32bits version, and the WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer errors are completely gone.

At some points before reinstalling, the Raspberry Pi was even losing the network after a few hours.
Everything is solved by moving back to the 32bits edition.

My model is: Raspberry Pi 3 Model B Plus Rev 1.3

@pelwell
Copy link
Contributor

pelwell commented Nov 9, 2023

The dwc_otg's driver makes heavy use of FIQs to improve performance. Until fairly recently the arm64 support didn't recognise FIQs; it looks like they are now supported to some extent, but the dwc_otg driver hasn't been updated to use it - you can bet that won't be a simple task.

For now, the advice on arm64 is the same as it's always been - use "dtoverlay=dwc2" to select the upstream DWC2 driver.

@sirjeannot
Copy link

sirjeannot commented Jan 7, 2024 •
edited
Loading

thank you for the pointer. however it appears to not be possible to boot from a usb drive using the dwc2 driver.
dtoverlay=dwc2,dr_mode=host

also disabling fiq in config.txt isn't taken into account by the driver, if it is even still possible.
https://forums.raspberrypi.com/viewtopic.php?t=70437

dwc_otg.fiq_enable=0
dwc_otg.fiq_fsm_enable=0

$ dmesg | grep FIQ
dwc_otg: FIQ enabled
dwc_otg: FIQ split-transaction FSM enabled

I'll revert to armv7l as well.

edit : reverted to armv7l, issue does still appear, but much less often.
edit2 : moved to emmc on sd adapter, no more issues.

@mthmulders
Copy link

Same, 64-bit Debian Bookworm on a Raspberry Pi 3+. I'm also using a USB-to-RJ45 cable to read my smart energy meter.

@popcornmix
Copy link
Contributor

also disabling fiq in config.txt isn't taken into account by the driver, if it is even still possible.
dwc_otg.fiq_enable=0
dwc_otg.fiq_fsm_enable=0

These setting go in cmdline.txt (on existing line), not config.txt.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
7 participants
@mthmulders @popcornmix @iz8mbw @pelwell @sirjeannot @vrobert78 @usaf-barnes