Zigbee stop working after 1.14.4 update

General support
1

Hello,
zigbee stopeed after updating to 1.14.4 (from 1.14.3).

I’m using RPi4 and cc2531 since 6 month

I was using same hardware from 6 month with no problem, the problem appeared just afterthe update and Home Assistant restart.

I did a reset of config file and then put on it again mqtt serve, user and password.
This is the configuration file:

data_path: /share/zigbee2mqtt
external_converters: []
devices: devices.yaml
groups: groups.yaml
homeassistant: true
permit_join: false
mqtt:
  base_topic: zigbee2mqtt
  server: 'mqtt://192.168.1.223'
  user: utente
  password: password
serial:
  port: /dev/ttyACM0
advanced:
  log_level: info
  pan_id: 6754
  channel: 11
  network_key:
    - 1
    - 3
    - 5
    - 7
    - 9
    - 11
    - 13
    - 15
    - 0
    - 2
    - 4
    - 6
    - 8
    - 10
    - 12
    - 13
  availability_blocklist: []
  availability_passlist: []
device_options: {}
blocklist: []
passlist: []
queue: {}
experimental: {}
socat:
  enabled: false
  master: 'pty,raw,echo=0,link=/dev/ttyZ2M,mode=777'
  slave: 'tcp-listen:8485,keepalive,nodelay,reuseaddr,keepidle=1,keepintvl=1,keepcnt=5'
  restartdelay: 1
  initialdelay: 1
  options: '-d -d'
  log: false

The cc2531 is on port:

serial:
  - /dev/ttyACM0
  - >-
    /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B0007678B96-if00
  - /dev/ttyAMA0

This is the error log:

2020-09-10T02:15:04: PM2 log: App [npm:0] exited with code [1] via signal [SIGINT]
2020-09-10T02:15:04: PM2 log: App [npm:0] starting in -fork mode-
2020-09-10T02:15:04: PM2 log: App [npm:0] online
> zigbee2mqtt@1.14.4 start /zigbee2mqtt-1.14.4
> node index.js
Zigbee2MQTT:info  2020-09-10 02:15:06: Logging to console and directory: '/share/zigbee2mqtt/log/2020-09-10.02-15-06' filename: log.txt
Zigbee2MQTT:info  2020-09-10 02:15:07: Starting Zigbee2MQTT version 1.14.4 (commit #unknown)
Zigbee2MQTT:info  2020-09-10 02:15:07: Starting zigbee-herdsman...
Zigbee2MQTT:error 2020-09-10 02:15:47: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2020-09-10 02:15:47: Failed to start zigbee
Zigbee2MQTT:error 2020-09-10 02:15:47: Exiting...
Zigbee2MQTT:error 2020-09-10 02:15:47: Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
    at ZStackAdapter.<anonymous> (/zigbee2mqtt-1.14.4/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:90:31)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.14.4/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:25:65)
npm
 ERR! code ELIFECYCLE
npm ERR! errno 1
npm
 ERR! zigbee2mqtt@1.14.4 start: `node index.js`
npm ERR! Exit status 1
npm ERR! 
npm ERR! Failed at the zigbee2mqtt@1.14.4 start script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
npm ERR! A complete log of this run can be found in:
npm ERR!     /root/.npm/_logs/2020-09-10T00_16_22_753Z-debug.log

Firmware on CC2531 with same already used for 6 month.

Please can some one help me with some suggestion?

Thank you
GiZeta

2

I did a restore of a previus snapshot but still same error:

Zigbee2MQTT:info  2020-09-10 10:18:21: Starting Zigbee2MQTT version 1.14.3 (commit #unknown)
Zigbee2MQTT:info  2020-09-10 10:18:21: Starting zigbee-herdsman...
Zigbee2MQTT:error 2020-09-10 10:19:01: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2020-09-10 10:19:01: Failed to start zigbee
Zigbee2MQTT:error 2020-09-10 10:19:01: Exiting...
Zigbee2MQTT:error 2020-09-10 10:19:01: Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
    at ZStackAdapter.<anonymous> (/zigbee2mqtt-1.14.3/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:87:31)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.14.3/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:25:65)
3

I don’t know, maybe try the second serial port. Unplugging and replugging your CC2531 might help too.

4

unfortunatly I had to reflash the cc2531 and then redo the pairing

so the cc2531 don’t has hardware problem because it’s working after re-flash the firmware

it’s weird that it was the update

my problem is solved but I did not understand why it happend

5

The problem seems to be similar than mine:
https://zigbee2mqtt.discourse.group/t/cc2531-stops-operating

Du you use groups? Or IKEA LED1837R5?

6

I don’t use Ikea products, what do you mean for “Du you use groups?”, I use grous to show device status un main dashboard, do you mean that?

7

Got exactly the same issue after upgrade to 1.14.4.1
If re-flashing is required it might be challenging as I’ve bout my adaptor pre-flash and don’t have programator

8

I mean whether you use the groups feature to combine devices.
I try to find any similarities to my configuration to locate the issue

9

The same problem.
Raspberry Pi 3B+
CC2531 dongle
Zigbee2MQTT as add-on into Home Assistant
Permanently this error in logs:

Zigbee2MQTT:info  2020-09-22 18:38:59: Starting zigbee-herdsman...
Zigbee2MQTT:error 2020-09-22 18:39:23: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2020-09-22 18:39:23: Failed to start zigbee
Zigbee2MQTT:error 2020-09-22 18:39:23: Exiting...
Zigbee2MQTT:error 2020-09-22 18:39:23: Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
    at ZStackAdapter.<anonymous> (/zigbee2mqtt-1.14.4/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:90:31)
    at Generator.throw (<anonymous>)
    at rejected (/zigbee2mqtt-1.14.4/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:25:65)
npm
 ERR! code ELIFECYCLE
npm ERR! errno 1
npm
 ERR! zigbee2mqtt@1.14.4 start: `node index.js`
npm ERR! Exit status 1
npm ERR! 
npm ERR! Failed at the zigbee2mqtt@1.14.4 start script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
npm ERR! A complete log of this run can be found in:
npm ERR!     /root/.npm/_logs/2020-09-22T15_39_24_151Z-debug.log
2020-09-22T18:39:25: PM2 log: App [npm:0] exited with code [1] via signal [SIGINT]

Update add-on to latest version – nothing changes.
Add-on config:

data_path: /share/zigbee2mqtt
external_converters: []
devices: devices.yaml
groups: groups.yaml
homeassistant: true
permit_join: false
mqtt:
  base_topic: zigbee2mqtt
  server: 'mqtt://core-mosquitto:1883'
  user: '!secret mqtt_username'
  password: '!secret mqtt_password'
serial:
  port: /dev/ttyACM0
advanced:
  log_level: info
  pan_id: 6754
  channel: 11
  network_key: []
  network_key_string: '!secret zigbee2mqt_network_key'
  availability_blocklist: []
  availability_passlist: []
device_options: {}
blocklist: []
passlist: []
queue: {}
experimental: {}
socat:
  enabled: false
  master: 'pty,raw,echo=0,link=/dev/ttyZ2M,mode=777'
  slave: 'tcp-listen:8485,keepalive,nodelay,reuseaddr,keepidle=1,keepintvl=1,keepcnt=5'
  restartdelay: 1
  initialdelay: 1
  options: '-d -d'
  log: false

I checked: the dongle is perfectly detected on the /dev/ttyACM0 port.

Previously, everything worked fine with the same settings. When it broke, I can’t say for sure.

10

It is a problem with the HA add-on. Zigbee2mqtt 1.14.4 works perfect when installed on bare metal.

11

Same problem, same setup as @Limych .

Replugged the C2531, downgraded to 1.14.0 and things are fortunately working again.

12

How did you downgrade to 1.14.0 ? I’m using Zigbee2MQTT as add-on into Home Assistant and I have the same problem.

13

I reflashed cc2531 too and it worked for a while, but, after 1 or 2 days the same problem popped up again.

14

Hi,

I have the exact same problem. The CC2531 stick randomly freezes after a while, or when sending a bunch of commands.

I reflashed the stick, but it does not solve the problem.

Only way to solve this at the moment is by replugging the stick, and restarting the add-on. It’s very annoying because my whole house relies upon all the zigbee devices… ( lights/heating etc ).

How to downgrade?

15

How did you install Zigbee2mqtt ?

16

Same problem after a restart all zigbee devices are gone and the zigbee2mqtt log shows a lot of errors. But after about 10 minutes the errors are gone and all works. This is also after the 1.14.4 update.

17

Same problem here, I have to replugging the stick and retart the add-on every time I restart Raspberry. But only in this case, if I restart only HomeAssistat it works well. copy-paste more info I had publish in Homme assistant Forum:

I have several zigbee devices and everything works fine but every time I restart the raspberry the cc2531 seems to stop working (in fact the green light is not set) and I have to disconnect it from the usb and reconnect it and then it works again and it turns on the green light.

It happens to me both connected with a usb extension cable and connected directly to the raspberry. The Raspberry is powered by a poe rj45 -> usb c converter, but if I powered it through the charger that I got with the raspberry, the same thing happens. Any idea how to fix it?

Thank you so much.

HassOS 4.15
HA 0.117.4
zigbee2mqtt 1.16.1
Cc2531: https://www.ebay.es/itm/CC2531-ANTENNA-FREE-CASE-ZigBee2MQTT-Pre-flashed-Coordinator-Router-ZigBee-/113926193098?nav=SEARCH cc2531 + antena - Coordinator

Hardware after restart:

serial:
  - /dev/ttyACM0
  - >-
    /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B001949C1DF-if00
  - /dev/ttyAMA0

Log:

[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] done.
[services.d] starting services
[services.d] done.
./run.sh: line 17: [Info] Configuration backup found in /share/zigbee2mqtt/.configuration.yaml.bk. Skipping config backup.: No such file or directory
[Info] Socat is DISABLED and not started
2020-11-07T00:28:52: PM2 log: Launching in no daemon mode
2020-11-07T00:28:53: PM2 log: App [npm:0] starting in -fork mode-
2020-11-07T00:28:53: PM2 log: App [npm:0] online
> zigbee2mqtt@1.16.1 start /zigbee2mqtt-1.16.1
> node index.js
Zigbee2MQTT:info  2020-11-07 00:28:57: Logging to console and directory: '/share/zigbee2mqtt/log/2020-11-07.00-28-56' filename: log.txt
Zigbee2MQTT:info  2020-11-07 00:28:57: Starting Zigbee2MQTT version 1.16.1 (commit #1.16.1)
Zigbee2MQTT:info  2020-11-07 00:28:57: Starting zigbee-herdsman...
Zigbee2MQTT:error 2020-11-07 00:28:57: Error while starting zigbee-herdsman
Zigbee2MQTT:error 2020-11-07 00:28:57: Failed to start zigbee
Zigbee2MQTT:error 2020-11-07 00:28:57: Exiting...
Zigbee2MQTT:error 2020-11-07 00:28:57: Error: Error while opening serialport 'Error: Error: No such file or directory, cannot open /dev/ttyACM1'
    at Znp.<anonymous> (/zigbee2mqtt-1.16.1/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:136:32)
    at Generator.next (<anonymous>)
    at /zigbee2mqtt-1.16.1/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:27:71
    at new Promise (<anonymous>)
    at __awaiter (/zigbee2mqtt-1.16.1/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:23:12)
    at SerialPort.<anonymous> (/zigbee2mqtt-1.16.1/node_modules/zigbee-herdsman/dist/adapter/z-stack/znp/znp.js:134:49)
    at SerialPort._error (/zigbee2mqtt-1.16.1/node_modules/zigbee-herdsman/node_modules/@serialport/stream/lib/index.js:198:14)
    at /zigbee2mqtt-1.16.1/node_modules/zigbee-herdsman/node_modules/@serialport/stream/lib/index.js:242:12
npm
 ERR! code ELIFECYCLE
npm ERR! errno 1
npm
 ERR! zigbee2mqtt@1.16.1 start: `node index.js`
npm ERR! Exit status 1
npm ERR! 
npm ERR! Failed at the zigbee2mqtt@1.16.1 start script.
npm ERR! This is probably not a problem with npm. There is likely additional logging output above.

Hardware after disconect and conect cc2531 (working again):

serial:
  - /dev/ttyACM1
  - >-
    /dev/serial/by-id/usb-Texas_Instruments_TI_CC2531_USB_CDC___0X00124B001949C1DF-if00
  - /dev/ttyAMA0

Log working:

> node index.js
Zigbee2MQTT:info  2020-11-07 00:49:16: Logging to console and directory: '/share/zigbee2mqtt/log/2020-11-07.00-49-15' filename: log.txt
Zigbee2MQTT:info  2020-11-07 00:49:16: Starting Zigbee2MQTT version 1.16.1 (commit #1.16.1)
Zigbee2MQTT:info  2020-11-07 00:49:16: Starting zigbee-herdsman...
Zigbee2MQTT:info  2020-11-07 00:49:19: zigbee-herdsman started
Zigbee2MQTT:info  2020-11-07 00:49:19: Coordinator firmware version: '{"meta":{"maintrel":3,"majorrel":2,"minorrel":6,"product":0,"revision":20190608,"transportrev":2},"type":"zStack12"}'
Zigbee2MQTT:info  2020-11-07 00:49:19: Currently 14 devices are joined:
Zigbee2MQTT:info  2020-11-07 00:49:19: 0x00124b001938c406 (0x00124b001938c406): CC2530.ROUTER - Custom devices (DiY) [CC2530 router](http://ptvo.info/cc2530-based-zigbee-coordinator-and-router-112/) (Router)
...
18

Same problem here with a 2 weeks old cc2531 usb.

  • rpi3b+
  • cc2531 usb
  • node-red
  • Ikea bulbs and dimmers

my cc2531 usb stick stopped working, green LED turns off when starting zigbee2mqtt.

Log says “Error while opening serialport”

Tried everything.

  • rebooting
  • rebuild npm
  • testing if dev/ttyACM0 is “success”
  • unpluging
  • reset cc2531 after plugin before starting zigbeemqtt
  • updating zigbee2mqtt to 1.16.1.
  • flashing with “default” firmware (after this the green LED dont turn off when starting zigbee2mqtt)
  • usb cord extender

nothing works.

log:

Nov 17 22:34:19 raspberrypi systemd[1]: Started zigbee2mqtt.
Nov 17 22:34:21 raspberrypi npm[488]: > zigbee2mqtt@1.16.1 start /opt/zigbee2mqtt
Nov 17 22:34:21 raspberrypi npm[488]: > node index.js
Nov 17 22:34:25 raspberrypi npm[488]: Zigbee2MQTT:info  2020-11-17 22:34:25: Logging to console and directory: '/opt/zigbee2mqtt/data/log/2020-11-17.22-34-23' filename: log.txt
Nov 17 22:34:25 raspberrypi npm[488]: Zigbee2MQTT:info  2020-11-17 22:34:25: Starting Zigbee2MQTT version 1.16.1 (commit #6b32f30)
Nov 17 22:34:25 raspberrypi npm[488]: Zigbee2MQTT:info  2020-11-17 22:34:25: Starting zigbee-herdsman...
Nov 17 22:34:56 raspberrypi npm[488]: Zigbee2MQTT:error 2020-11-17 22:34:56: Error while starting zigbee-herdsman
Nov 17 22:34:56 raspberrypi npm[488]: Zigbee2MQTT:error 2020-11-17 22:34:56: Failed to start zigbee
Nov 17 22:34:56 raspberrypi npm[488]: Zigbee2MQTT:error 2020-11-17 22:34:56: Exiting...
Nov 17 22:34:56 raspberrypi npm[488]: Zigbee2MQTT:error 2020-11-17 22:34:56: Error: Failed to connect to the adapter (Error: SRSP - SYS - ping after 6000ms)
Nov 17 22:34:56 raspberrypi npm[488]:     at ZStackAdapter.<anonymous> (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:94:31)
Nov 17 22:34:56 raspberrypi npm[488]:     at Generator.throw (<anonymous>)
Nov 17 22:34:56 raspberrypi npm[488]:     at rejected (/opt/zigbee2mqtt/node_modules/zigbee-herdsman/dist/adapter/z-stack/adapter/zStackAdapter.js:25:65)
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! code ELIFECYCLE
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! errno 1
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! zigbee2mqtt@1.16.1 start: `node index.js`
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! Exit status 1
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR!
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! Failed at the zigbee2mqtt@1.16.1 start script.
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! This is probably not a problem with npm. There is likely additional logging output above.
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR! A complete log of this run can be found in:
Nov 17 22:34:56 raspberrypi npm[488]: npm ERR!     /home/pi/.npm/_logs/2020-11-17T21_34_56_475Z-debug.log
Nov 17 22:34:56 raspberrypi systemd[1]: zigbee2mqtt.service: Main process exited, code=exited, status=1/FAILURE
Nov 17 22:34:56 raspberrypi systemd[1]: zigbee2mqtt.service: Failed with result 'exit-code'.
Nov 17 22:34:56 raspberrypi systemd[1]: zigbee2mqtt.service: Service RestartSec=100ms expired, scheduling restart.
Nov 17 22:34:56 raspberrypi systemd[1]: zigbee2mqtt.service: Scheduled restart job, restart counter is at 1.
Nov 17 22:34:56 raspberrypi systemd[1]: Stopped zigbee2mqtt.
19

here is the dmesg -w

It has some “invalid offsets” and “failed to set dtr/rts”
what erver tant means?

dmesg -w

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.4.72-v7+ (dom@buildbot) (gcc version 9.3.0 (Ubuntu 9.3.0-17ubuntu1~20.04)) #1356 SMP Thu Oct 22 13:56:54 BST 2020
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] On node 0 totalpages: 242688
[    0.000000]   Normal zone: 2133 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 242688 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s49740 r8192 d23988 u81920
[    0.000000] pcpu-alloc: s49740 r8192 d23988 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=c0f6f7b0-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 880468K/970752K available (9216K kernel code, 704K rwdata, 2628K rodata, 1024K init, 829K bss, 24748K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 29179 entries in 57 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] random: get_random_bytes called from start_kernel+0x328/0x510 with crng_init=0
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000021] Switching to timer-based delay loop, resolution 52ns
[    0.000282] Console: colour dummy device 80x30
[    0.000874] printk: console [tty1] enabled
[    0.000940] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.000989] pid_max: default: 32768 minimum: 301
[    0.001197] LSM: Security Framework initializing
[    0.001434] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001481] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002885] Disabling memory control group subsystem
[    0.003027] CPU: Testing write buffer coherency: ok
[    0.003615] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004594] Setting up static identity map for 0x100000 - 0x10003c
[    0.004816] rcu: Hierarchical SRCU implementation.
[    0.005531] smp: Bringing up secondary CPUs ...
[    0.006693] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.007995] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.009182] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009335] smp: Brought up 1 node, 4 CPUs
[    0.009426] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.009454] CPU: All CPU(s) started in HYP mode.
[    0.009480] CPU: Virtualization extensions available.
[    0.010578] devtmpfs: initialized
[    0.026981] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.027292] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.027348] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.030667] pinctrl core: initialized pinctrl subsystem
[    0.031807] NET: Registered protocol family 16
[    0.036122] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.040989] audit: initializing netlink subsys (disabled)
[    0.041298] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.042727] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.042767] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.043000] Serial: AMBA PL011 UART driver
[    0.045026] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.070095] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-10-22 14:06, variant start
[    0.080108] raspberrypi-firmware soc:firmware: Firmware hash is 74e754ff8947c58d2773253f77f6f68a303188f8
[    0.131288] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.133503] SCSI subsystem initialized
[    0.133768] usbcore: registered new interface driver usbfs
[    0.133851] usbcore: registered new interface driver hub
[    0.134011] usbcore: registered new device driver usb
[    0.135971] clocksource: Switched to clocksource arch_sys_counter
[    1.298172] VFS: Disk quotas dquot_6.6.0
[    1.298312] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.298531] FS-Cache: Loaded
[    1.298825] CacheFiles: Loaded
[    1.310188] thermal_sys: Registered thermal governor 'step_wise'
[    1.310574] NET: Registered protocol family 2
[    1.311656] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.311733] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    1.311875] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.312086] TCP: Hash tables configured (established 8192 bind 8192)
[    1.312274] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.312348] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.312662] NET: Registered protocol family 1
[    1.313473] RPC: Registered named UNIX socket transport module.
[    1.313505] RPC: Registered udp transport module.
[    1.313532] RPC: Registered tcp transport module.
[    1.313558] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.315309] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    1.318743] Initialise system trusted keyrings
[    1.319093] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    1.330758] FS-Cache: Netfs 'nfs' registered for caching
[    1.331622] NFS: Registering the id_resolver key type
[    1.331712] Key type id_resolver registered
[    1.331739] Key type id_legacy registered
[    1.331774] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.333089] Key type asymmetric registered
[    1.333119] Asymmetric key parser 'x509' registered
[    1.333199] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.333237] io scheduler mq-deadline registered
[    1.333265] io scheduler kyber registered
[    1.336798] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.349499] Console: switching to colour frame buffer device 82x26
[    1.356563] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
[    1.366496] bcm2835-rng 3f104000.rng: hwrng registered
[    1.369551] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.375273] vc-sm: Videocore shared memory driver
[    1.378336] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    1.396306] brd: module loaded
[    1.411260] loop: module loaded
[    1.415145] Loading iSCSI transport class v2.0-870.
[    1.418766] libphy: Fixed MDIO Bus: probed
[    1.421440] usbcore: registered new interface driver lan78xx
[    1.424079] usbcore: registered new interface driver smsc95xx
[    1.426682] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.157455] Core Release: 2.80a
[    2.159864] Setting default values for core params
[    2.162305] Finished setting default values for core params
[    2.365183] Using Buffer DMA mode
[    2.367668] Periodic Transfer Interrupt Enhancement - disabled
[    2.370181] Multiprocessor Interrupt Enhancement - disabled
[    2.372667] OTG VER PARAM: 0, OTG VER FLAG: 0
[    2.375082] Dedicated Tx FIFOs mode
[    2.378073] WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = b7514000 dma = 0xf7514000 len=9024
[    2.382823] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    2.393979] dwc_otg: Microframe scheduler enabled
[    2.394056] WARN::hcd_init_fiq:457: FIQ on core 1
[    2.396315] WARN::hcd_init_fiq:458: FIQ ASM at 80716d40 length 36
[    2.398581] WARN::hcd_init_fiq:496: MPHI regs_base at bb810000
[    2.400813] dwc_otg 3f980000.usb: DWC OTG Controller
[    2.403136] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    2.405519] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000
[    2.407867] Init: Port Power? op_state=1
[    2.410129] Init: Power Port (0)
[    2.412690] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    2.417265] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.419698] usb usb1: Product: DWC OTG Controller
[    2.422084] usb usb1: Manufacturer: Linux 5.4.72-v7+ dwc_otg_hcd
[    2.424523] usb usb1: SerialNumber: 3f980000.usb
[    2.427641] hub 1-0:1.0: USB hub found
[    2.429960] hub 1-0:1.0: 1 port detected
[    2.432900] dwc_otg: FIQ enabled
[    2.432910] dwc_otg: NAK holdoff enabled
[    2.432920] dwc_otg: FIQ split-transaction FSM enabled
[    2.432935] Module dwc_common_port init
[    2.433264] usbcore: registered new interface driver usb-storage
[    2.435770] mousedev: PS/2 mouse device common for all mice
[    2.439310] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    2.444611] sdhci: Secure Digital Host Controller Interface driver
[    2.447089] sdhci: Copyright(c) Pierre Ossman
[    2.450056] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
[    2.453230] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    2.456048] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.460470] ledtrig-cpu: registered to indicate activity on CPUs
[    2.463433] hidraw: raw HID events driver (C) Jiri Kosina
[    2.466234] usbcore: registered new interface driver usbhid
[    2.468832] usbhid: USB HID core driver
[    2.472431] vchiq: vchiq_init_state: slot_zero = (ptrval)
[    2.476803] [vc_sm_connected_init]: start
[    2.485598] [vc_sm_connected_init]: end - returning 0
[    2.490039] Initializing XFRM netlink socket
[    2.492429] NET: Registered protocol family 17
[    2.494909] Key type dns_resolver registered
[    2.497952] Registering SWP/SWPB emulation handler
[    2.500625] registered taskstats version 1
[    2.503001] Loading compiled-in X.509 certificates
[    2.505863] Key type ._fscrypt registered
[    2.508214] Key type .fscrypt registered
[    2.521347] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    2.523768] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2
[    2.530375] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    2.534281] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    2.536722] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    2.565584] sdhost: log_buf @ (ptrval) (f7513000)
[    2.605139] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    2.609142] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    2.613021] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    2.617499] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    2.622237] of_cfs_init
[    2.624624] of_cfs_init: OK
[    2.627712] Waiting for root device PARTUUID=c0f6f7b0-02...
[    2.640193] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    2.646134] Indeed it is in host mode hprt0 = 00021501
[    2.720373] random: fast init done
[    2.742090] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.749904] mmc0: new high speed SDXC card at address 59b4
[    2.753525] mmcblk0: mmc0:59b4 EC1S5 59.7 GiB
[    2.759589]  mmcblk0: p1 p2
[    2.785713] mmc1: new high speed SDIO card at address 0001
[    2.791440] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.796030] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.806545] devtmpfs: mounted
[    2.815843] Freeing unused kernel memory: 1024K
[    2.826434] Run /sbin/init as init process
[    2.856029] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.858756] Indeed it is in host mode hprt0 = 00001101
[    3.096411] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    3.101774] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.105405] hub 1-1:1.0: USB hub found
[    3.108410] hub 1-1:1.0: 4 ports detected
[    3.426034] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    3.449307] systemd[1]: System time before build time, advancing clock.
[    3.556410] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    3.561933] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.566031] hub 1-1.1:1.0: USB hub found
[    3.569064] hub 1-1.1:1.0: 3 ports detected
[    3.585773] NET: Registered protocol family 10
[    3.590057] Segment Routing with IPv6
[    3.641006] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    3.650462] systemd[1]: Detected architecture arm.
[    3.666029] usb 1-1.2: new full-speed USB device number 4 using dwc_otg
[    3.737730] systemd[1]: Set hostname to <raspberrypi>.
[    3.815133] usb 1-1.2: New USB device found, idVendor=0451, idProduct=16a8, bcdDevice= 0.09
[    3.821303] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.824484] usb 1-1.2: Product: TI CC2531 USB CDC
[    3.830151] usb 1-1.2: Manufacturer: Texas Instruments
[    3.833200] usb 1-1.2: SerialNumber: __0X00124B0019389D89
[    4.346130] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    4.476669] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
[    4.482154] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.569448] random: systemd: uninitialized urandom read (16 bytes read)
[    4.587397] random: systemd: uninitialized urandom read (16 bytes read)
[    4.591218] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    4.600953] random: systemd: uninitialized urandom read (16 bytes read)
[    4.604533] systemd[1]: Listening on fsck to fsckd communication Socket.
[    4.611716] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    4.624123] systemd[1]: Created slice User and Session Slice.
[    4.631332] systemd[1]: Reached target Slices.
[    4.639096] systemd[1]: Listening on udev Control Socket.
[    4.646460] systemd[1]: Listening on udev Kernel Socket.
[    4.760821] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    4.767780] libphy: lan78xx-mdiobus: probed
[    4.815268] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    5.569499] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    5.687231] systemd-journald[110]: Received request to flush runtime journal from PID 1
[    6.727032] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    6.731425] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    6.731449] [vc_sm_connected_init]: start
[    6.737298] [vc_sm_connected_init]: installed successfully
[    6.740814] mc: Linux media interface: v0.10
[    6.798941] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    6.805872] bcm2835_audio bcm2835_audio: card created with 8 channels
[    6.831739] videodev: Linux video capture interface: v2.00
[    6.861132] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.862218] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.863107] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    6.870984] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    6.873416] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    6.884295] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    6.887314] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    6.890236] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    6.890667] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    6.890698] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    6.890720] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    6.890740] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    6.890759] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    6.890976] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    6.891757] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    6.902977] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    6.903025] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    6.908934] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    6.908982] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    6.930929] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    6.930997] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.262633] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.305730] cdc_acm 1-1.2:1.0: ttyACM0: USB ACM device
[    7.308939] usbcore: registered new interface driver cdc_acm
[    7.308956] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    7.418993] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    7.556989] brcmfmac: F1 signature read @0x18000000=0x15264345
[    7.574539] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.575144] usbcore: registered new interface driver brcmfmac
[    7.621283] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2
[    7.835200] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.850932] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[    9.288492] random: crng init done
[    9.288527] random: 7 urandom warning(s) missed due to ratelimiting
[    9.685813] 8021q: 802.1Q VLAN Support v1.8
[    9.926093] Adding 102396k swap on /var/swap.  Priority:-2 extents:2 across:307200k SSFS
[   10.390889] 8021q: adding VLAN 0 to HW filter on device eth0
[   10.395164] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   35.843616] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 5
[   41.879547] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[   47.144587] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[   60.629112] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[   66.655670] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[   71.828426] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[   72.293370] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[   79.302345] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 3
[   91.337167] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  101.563184] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  101.724973] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[  121.075769] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  126.138916] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  126.301979] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  145.647618] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  150.889563] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  151.355847] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[  152.362738] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[  164.372359] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[  170.400379] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  175.612299] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  195.123907] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  210.884171] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  212.359085] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[  218.360738] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  230.394930] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  235.650298] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  255.167770] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  260.395744] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  260.708998] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[  260.862624] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[  264.453887] usb 1-1.2: USB disconnect, device number 4
[  264.454108] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  266.546215] usb 1-1.2: new full-speed USB device number 6 using dwc_otg
[  267.164070] usb 1-1.2: New USB device found, idVendor=0451, idProduct=16a8, bcdDevice= 0.09
[  267.164088] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  267.164102] usb 1-1.2: Product: TI CC2531 USB CDC
[  267.164116] usb 1-1.2: Manufacturer: Texas Instruments
[  267.164130] usb 1-1.2: SerialNumber: __0X00124B0019389D89
[  267.166803] cdc_acm 1-1.2:1.0: ttyACM0: USB ACM device
[  292.529919] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  302.876958] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  303.343375] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[  322.389521] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  327.650055] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  341.132857] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 5
[  347.161170] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  352.423914] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  365.906000] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  371.934435] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  382.400466] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  383.875340] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[  401.918420] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  407.130171] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  414.604478] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 2
[  426.639049] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  431.898764] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  432.060969] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 2
[  432.365730] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 0
[  451.410456] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  483.165083] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  490.640981] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[  496.647980] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  502.674998] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  507.905673] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  515.380353] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 7
[  521.388241] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[  527.416041] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  537.873295] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  538.035997] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[  545.348231] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 5
[  551.356352] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 0
[  557.385734] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  562.631635] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  576.113359] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  582.141687] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  594.881673] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  598.793973] usb 1-1.2: USB disconnect, device number 6
[  598.794204] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  601.396290] usb 1-1.2: new full-speed USB device number 7 using dwc_otg
[  601.544064] usb 1-1.2: New USB device found, idVendor=0451, idProduct=16a8, bcdDevice= 0.09
[  601.544084] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  601.544098] usb 1-1.2: Product: TI CC2531 USB CDC
[  601.544112] usb 1-1.2: Manufacturer: Texas Instruments
[  601.544126] usb 1-1.2: SerialNumber: __0X00124B0019389D89
[  601.546771] cdc_acm 1-1.2:1.0: ttyACM0: USB ACM device
[  626.985767] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  645.578683] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  647.053365] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  665.093790] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  669.948380] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  670.418372] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 3
[  671.425249] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[  689.484763] cdc_acm 1-1.2:1.0: failed to set dtr/rts

[  831.932912] hptxfsiz_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  831.932987] gnptxfsiz_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  831.933062] hnpcapable_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  831.933155] grxfsiz_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  831.933229] gotgctl_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  831.933381] HZ 100, MSEC_PER_JIFFIE 10, loops_per_jiffy 448000
[  832.301814] Core is not in hibernation
[  832.301857] WARN::dwc_otg_handle_mode_mismatch_intr:67: Mode Mismatch Interrupt: currently in Host mode

[  832.301987] hprt0_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302060] ggpio_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302134] hsic_connect_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302207] busconnected_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302408] inv_sel_hsic_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302577] gsnpsid_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302674] devspeed_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302692] WARN::dwc_otg_handle_mode_mismatch_intr:67: Mode Mismatch Interrupt: currently in Host mode

[  832.302750] dwc_otg 3f980000.usb: Invalid offset (0xffffffff)
[  832.302863] srpcapable_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.302937] guid_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.303023] gpvndctl_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.303096] gusbcfg_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.303358] mode_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  832.303495] HZ 100, MSEC_PER_JIFFIE 10, loops_per_jiffy 448000
[  833.224268] enumspeed_show(3d9e9b4c) -> platform_dev f75e9ccd, otg_dev 85cb0fd0
[  833.224376] WARN::dwc_otg_handle_mode_mismatch_intr:67: Mode Mismatch Interrupt: currently in Host mode

[  849.700334] cdc_acm 1-1.2:1.0: failed to set dtr/rts
[  850.166739] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 6
[  851.174734] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 1
[  863.183597] WARN::dwc_otg_hcd_urb_dequeue:638: Timed out waiting for FSM NP transfer to complete on 4
[  869.214876] cdc_acm 1-1.2:1.0: failed to set dtr/rts
20

Googled the error from the dmesg.

found this:

and tried the suggestion of using the source_routing firmware, and that worked. :partying_face: