search

raspberrypi / firmware

This repository contains pre-compiled binaries of the current Raspberry Pi kernel and modules, userspace libraries, and bootloader/GPU firmware.
5.18k stars 1.68k forks source link

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 1 year ago

iz8mbw commented 1 year ago

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 commented 1 year ago

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 commented 1 year ago

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 commented 1 year ago

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 commented 1 year ago

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 commented 10 months ago

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 commented 9 months ago

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 commented 9 months ago

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.