Modem manager causing our serial ports to fail

device
1

I am using Ubuntu Core 22 with Quectel modems which uses USB. Additionally, our embedded custom snap app also uses USB. All Serial.

Modem manager appears to be generating conflicts across our own USB serial devices. Maybe the probing or maybe something else.

It appears modem manager as a Snap does not honor the rules under /etc/udev/rules.d?

When the modem manager snap runs it looks like this:

/snap/modem-manager/542/usr/sbin/ModemManager --filter-policy=STRICT --debug --log-level=DEBUG

In two rule files (tried just one) I have placed this rule: ATTRS{idVendor}==“0403”, ATTRS{idProduct}=“6015”, ENV{ID_MM_DEVICE_IGNORE}=“1”

None of these things seem to prevent modem manager from causing havoc.

What do I need to do? Built my own modem manager snap and customize it?

Thanks, Will

2

Hey!

Thank you for reaching out – to verify if your rule is indeed loaded by udev, can you run

udevadm info <device> on the device you do not want MM to scan and post the output?

Also any logs from sudo journalctl -b -u snap.modem-manager.modemmanager.service after starting modem-manager would be nice too.

3

Here is what I get from the udevadm:

/dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6S5-if00-port0 P: /devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4/1-1.4.4:1.0/ttyUSB9/tty/ttyUSB9 N: ttyUSB9 L: 0 S: serial/by-path/platform-fe980000.usb-usb-0:1.4.4:1.0-port0 S: serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6S5-if00-port0 E: DEVPATH=/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4/1-1.4.4:1.0/ttyUSB9/tty/ttyUSB9 E: DEVNAME=/dev/ttyUSB9 E: MAJOR=188 E: MINOR=9 E: SUBSYSTEM=tty E: USEC_INITIALIZED=22287244 E: ID_VENDOR=Thiopoly E: ID_VENDOR_ENC=Thiopoly E: ID_VENDOR_ID=0403 E: ID_MODEL=FT230X_Basic_UART E: ID_MODEL_ENC=FT230X\x20Basic\x20UART E: ID_MODEL_ID=6015 E: ID_REVISION=1000 E: ID_SERIAL=Thiopoly_FT230X_Basic_UART_DU0DO6S5 E: ID_SERIAL_SHORT=DU0DO6S5 E: ID_TYPE=generic E: ID_BUS=usb E: ID_USB_INTERFACES=:ffffff: E: ID_USB_INTERFACE_NUM=00 E: ID_USB_DRIVER=ftdi_sio E: ID_VENDOR_FROM_DATABASE=Future Technology Devices International, Ltd E: ID_MODEL_FROM_DATABASE=Bridge(I2C/SPI/UART/FIFO) E: ID_PATH=platform-fe980000.usb-usb-0:1.4.4:1.0 E: ID_PATH_TAG=platform-fe980000_usb-usb-0_1_4_4_1_0 E: ID_MM_CANDIDATE=1 E: DEVLINKS=/dev/serial/by-path/platform-fe980000.usb-usb-0:1.4.4:1.0-port0 /dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6S5-if00-port0 E: TAGS=:snap_modem-manager_modemmanager:snap_modem-manager_mbim-network:snap_modem-manager_qmicli:snap_modem-manager_mbimcli:snap_network-manager_networkmanager:snap_modem-manager_qmi-network:systemd: E: CURRENT_TAGS=:snap_modem-manager_modemmanager:snap_modem-manager_mbim-network:snap_modem-manager_qmicli:snap_modem-manager_mbimcli:snap_network-manager_networkmanager:snap_modem-manager_qmi-network:systemd:

This is inside /etc/udev/rules.d/80-Thiopoly.rules

Thiopoly USBs

ATTRS{idVendor}==“0403”, ATTRS{idProduct}=“6015”, ENV{ID_MM_DEVICE_IGNORE}=“1”

I don’t see MM_DEVICE_IGNORE under Info? Which again seems to make me believe modem-manager is ignoring it.

I believe this may be due to the filter-policy=STRICT but for the snap unless you build your own snap one is unable to change this???

Thanks, WIll

4

Hey!

I just noticed, is there maybe a mistake in the rule, it seems you are using the wrong equality check here: ATTRS{idProduct}=“6015” instead of ATTRS{idProduct}==“6015".

If the ID_MM_DEVICE_IGNORE is not set in udevadm info, then the rule has not been applied correctly.

5

You are right, but after testing that fix it still does not honor it:

P: /devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4/1-1.4.4:1.0/ttyUSB10/tty/ttyUSB10 N: ttyUSB10 L: 0 S: serial/by-path/platform-fe980000.usb-usb-0:1.4.4:1.0-port0 S: serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6S5-if00-port0 E: DEVPATH=/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4/1-1.4.4:1.0/ttyUSB10/tty/ttyUSB10 E: DEVNAME=/dev/ttyUSB10 E: MAJOR=188 E: MINOR=10 E: SUBSYSTEM=tty E: USEC_INITIALIZED=21250621 E: ID_VENDOR=Thiopoly E: ID_VENDOR_ENC=Thiopoly E: ID_VENDOR_ID=0403 E: ID_MODEL=FT230X_Basic_UART E: ID_MODEL_ENC=FT230X\x20Basic\x20UART E: ID_MODEL_ID=6015 E: ID_REVISION=1000 E: ID_SERIAL=Thiopoly_FT230X_Basic_UART_DU0DO6S5 E: ID_SERIAL_SHORT=DU0DO6S5 E: ID_TYPE=generic E: ID_BUS=usb E: ID_USB_INTERFACES=:ffffff: E: ID_USB_INTERFACE_NUM=00 E: ID_USB_DRIVER=ftdi_sio E: ID_VENDOR_FROM_DATABASE=Future Technology Devices International, Ltd E: ID_MODEL_FROM_DATABASE=Bridge(I2C/SPI/UART/FIFO) E: ID_PATH=platform-fe980000.usb-usb-0:1.4.4:1.0 E: ID_PATH_TAG=platform-fe980000_usb-usb-0_1_4_4_1_0 E: ID_MM_CANDIDATE=1 E: DEVLINKS=/dev/serial/by-path/platform-fe980000.usb-usb-0:1.4.4:1.0-port0 /dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6S5-if00-port0 E: TAGS=:snap_modem-manager_qmi-network:snap_modem-manager_modemmanager:snap_modem-manager_qmicli:snap_network-manager_networkmanager:systemd:snap_modem-manager_mbim-network:snap_modem-manager_mbimcli: E: CURRENT_TAGS=:snap_modem-manager_qmi-network:snap_modem-manager_modemmanager:snap_modem-manager_qmicli:snap_network-manager_networkmanager:systemd:snap_modem-manager_mbim-network:snap_modem-manager_mbimcli:

root@thio-demo-0:/home/willthio# cat /etc/udev/rules.d/78-thiopoly.rules

Thiopoly USBs

ATTRS{idVendor}==“0403”, ATTRS{idProduct}==“6015”, ENV{ID_MM_DEVICE_IGNORE}==“1”

6

Can you try to run udevadm test DEVPATH to verify your rule gets executed correctly?

7

This is what is shows: (polling-loop) root@thio-demo-0:/var/snap/thio/current/ggc_user/utils# udevadm test /dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6SP-if00-port0 This program is for debugging only, it does not run any program specified by a RUN key. It may show incorrect results, because some values may be different, or not available at a simulation run.

Trying to open “/etc/systemd/hwdb/hwdb.bin”… Trying to open “/etc/udev/hwdb.bin”… Trying to open “/usr/lib/systemd/hwdb/hwdb.bin”… Trying to open “/lib/systemd/hwdb/hwdb.bin”… Trying to open “/lib/udev/hwdb.bin”… === trie on-disk === tool version: 249 file size: 11124855 bytes header size 80 bytes strings 2374711 bytes nodes 8750064 bytes Load module index Found cgroup2 on /sys/fs/cgroup/, full unified hierarchy Found container virtualization none. Loaded timestamp for ‘/etc/systemd/network’. Loaded timestamp for ‘/usr/lib/systemd/network’. Parsed configuration file /usr/lib/systemd/network/99-default.link Parsed configuration file /usr/lib/systemd/network/73-usb-net-by-mac.link Parsed configuration file /usr/lib/systemd/network/72-rpi4-net-by-kernel.link Parsed configuration file /usr/lib/systemd/network/72-rpi3-net-by-kernel.link Created link configuration context. Loaded timestamp for ‘/etc/udev/rules.d’. Reading rules file: /usr/lib/udev/rules.d/40-vm-hotadd.rules Reading rules file: /usr/lib/udev/rules.d/50-firmware.rules Reading rules file: /usr/lib/udev/rules.d/50-udev-default.rules Reading rules file: /usr/lib/udev/rules.d/55-dm.rules Reading rules file: /usr/lib/udev/rules.d/60-autosuspend.rules Reading rules file: /usr/lib/udev/rules.d/60-block.rules Reading rules file: /usr/lib/udev/rules.d/60-cdrom_id.rules Reading rules file: /usr/lib/udev/rules.d/60-drm.rules Reading rules file: /usr/lib/udev/rules.d/60-evdev.rules Reading rules file: /usr/lib/udev/rules.d/60-fido-id.rules Reading rules file: /usr/lib/udev/rules.d/60-input-id.rules Reading rules file: /usr/lib/udev/rules.d/60-persistent-alsa.rules Reading rules file: /usr/lib/udev/rules.d/60-persistent-input.rules Reading rules file: /usr/lib/udev/rules.d/60-persistent-storage-dm.rules Reading rules file: /usr/lib/udev/rules.d/60-persistent-storage-tape.rules Reading rules file: /usr/lib/udev/rules.d/60-persistent-storage.rules Reading rules file: /usr/lib/udev/rules.d/60-persistent-v4l.rules Reading rules file: /usr/lib/udev/rules.d/60-sensor.rules Reading rules file: /usr/lib/udev/rules.d/60-serial.rules Reading rules file: /usr/lib/udev/rules.d/61-persistent-storage-android.rules Reading rules file: /usr/lib/udev/rules.d/64-btrfs.rules Reading rules file: /usr/lib/udev/rules.d/66-azure-ephemeral.rules Reading rules file: /usr/lib/udev/rules.d/66-snapd-autoimport.rules Reading rules file: /usr/lib/udev/rules.d/70-joystick.rules Reading rules file: /usr/lib/udev/rules.d/70-memory.rules Reading rules file: /usr/lib/udev/rules.d/70-mouse.rules Reading rules file: /usr/lib/udev/rules.d/70-power-switch.rules Reading rules file: /etc/udev/rules.d/70-snap.avahi.rules Reading rules file: /etc/udev/rules.d/70-snap.modem-manager.rules Reading rules file: /etc/udev/rules.d/70-snap.network-manager.rules Reading rules file: /etc/udev/rules.d/70-snap.thio.rules Reading rules file: /usr/lib/udev/rules.d/70-touchpad.rules Reading rules file: /usr/lib/udev/rules.d/70-uaccess.rules Reading rules file: /usr/lib/udev/rules.d/71-nvidia.rules Reading rules file: /usr/lib/udev/rules.d/71-power-switch-proliant.rules Reading rules file: /usr/lib/udev/rules.d/71-seat.rules Reading rules file: /usr/lib/udev/rules.d/73-seat-late.rules Reading rules file: /usr/lib/udev/rules.d/73-special-net-names.rules Reading rules file: /usr/lib/udev/rules.d/75-net-description.rules Reading rules file: /usr/lib/udev/rules.d/75-probe_mtd.rules Reading rules file: /usr/lib/udev/rules.d/78-graphics-card.rules Reading rules file: /usr/lib/udev/rules.d/78-sound-card.rules Reading rules file: /etc/udev/rules.d/78-thiopoly.rules /etc/udev/rules.d/78-thiopoly.rules:2 The line takes no effect, ignoring. /etc/udev/rules.d/78-thiopoly.rules:9 The line takes no effect, ignoring. /etc/udev/rules.d/78-thiopoly.rules:10 The line takes no effect, ignoring. /etc/udev/rules.d/78-thiopoly.rules:11 The line takes no effect, ignoring. /etc/udev/rules.d/78-thiopoly.rules:12 The line takes no effect, ignoring. Reading rules file: /usr/lib/udev/rules.d/80-debian-compat.rules Reading rules file: /usr/lib/udev/rules.d/80-drivers.rules Reading rules file: /usr/lib/udev/rules.d/80-net-setup-link.rules Reading rules file: /usr/lib/udev/rules.d/81-net-dhcp.rules Reading rules file: /run/udev/rules.d/90-netplan.rules Reading rules file: /usr/lib/udev/rules.d/90-rtc-sys-time-init.rules Reading rules file: /usr/lib/udev/rules.d/95-dm-notify.rules Reading rules file: /usr/lib/udev/rules.d/96-e2scrub.rules Reading rules file: /usr/lib/udev/rules.d/99-systemd.rules Failed to open device ‘/sys/dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6SP-if00-port0’: No such device Unload module index Unloaded link configuration context.

8

Seems to be saying this line takes no effect?

Thiopoly USBs

ATTRS{idVendor}==“0403”, ATTRS{idProduct}==“6015”, ENV{ID_MM_DEVICE_IGNORE}==“1”

Why?

9

You changed equality sign of ENV{ID_MM_DEVICE_IGNORE}==“1”, this means that there is nothing being assigned, this needs to be a single =. The first two ATTRS should be == and the ENV needs to be =

10

Holy cow! Now I have the Ignore:

P: /devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.5/1-1.4.5:1.0/ttyUSB0/tty/ttyUSB0 N: ttyUSB0 L: 0 S: serial/by-path/platform-fe980000.usb-usb-0:1.4.5:1.0-port0 S: serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6SP-if00-port0 E: DEVPATH=/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.5/1-1.4.5:1.0/ttyUSB0/tty/ttyUSB0 E: DEVNAME=/dev/ttyUSB0 E: MAJOR=188 E: MINOR=0 E: SUBSYSTEM=tty E: USEC_INITIALIZED=21129161 E: ID_VENDOR=Thiopoly E: ID_VENDOR_ENC=Thiopoly E: ID_VENDOR_ID=0403 E: ID_MODEL=FT230X_Basic_UART E: ID_MODEL_ENC=FT230X\x20Basic\x20UART E: ID_MODEL_ID=6015 E: ID_REVISION=1000 E: ID_SERIAL=Thiopoly_FT230X_Basic_UART_DU0DO6SP E: ID_SERIAL_SHORT=DU0DO6SP E: ID_TYPE=generic E: ID_BUS=usb E: ID_USB_INTERFACES=:ffffff: E: ID_USB_INTERFACE_NUM=00 E: ID_USB_DRIVER=ftdi_sio E: ID_VENDOR_FROM_DATABASE=Future Technology Devices International, Ltd E: ID_MODEL_FROM_DATABASE=Bridge(I2C/SPI/UART/FIFO) E: ID_PATH=platform-fe980000.usb-usb-0:1.4.5:1.0 E: ID_PATH_TAG=platform-fe980000_usb-usb-0_1_4_5_1_0 E: ID_MM_CANDIDATE=1 E: ID_MM_DEVICE_IGNORE=1 E: DEVLINKS=/dev/serial/by-path/platform-fe980000.usb-usb-0:1.4.5:1.0-port0 /dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6SP-if00-port0 E: TAGS=:systemd:snap_network-manager_networkmanager:snap_modem-manager_mbimcli:snap_modem-manager_qmi-network:snap_modem-manager_mbim-network:snap_modem-manager_qmicli:snap_modem-manager_modemmanager: E: CURRENT_TAGS=:systemd:snap_network-manager_networkmanager:snap_modem-manager_mbimcli:snap_modem-manager_qmi-network:snap_modem-manager_mbim-network:snap_modem-manager_qmicli:snap_modem-manager_modemmanager:

11

So back to my original issue of collision. I am still seeing issues even after getting this ignore to work. What appears to be happening is a hang. Adding 7 of our devices works then the 8th just hangs. No device even appears under /dev/serial. When I remove modem-manager all works fine.

Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: [1756301343.931804] [base-manager] adding port ttyUSB8 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0/ttyUSB8/tty/ttyUSB8 Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: [1756301343.932198] [filter] (tty/ttyUSB8): port filtered: device is blocklisted Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.023891] [ttyUSB9] port contents loaded: Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025261] [ttyUSB9] bus: usb Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025377] [ttyUSB9] interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.2/1-1.2:1.0 Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025411] [ttyUSB9] device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.2 Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025442] [ttyUSB9] driver: ftdi_sio Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025469] [ttyUSB9] vendor: 0403 Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025496] [ttyUSB9] product: 6015 Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025527] [ttyUSB9] revision: 1000 Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025563] [base-manager] adding port ttyUSB9 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/ttyUSB9/tty/ttyUSB9 Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: [1756301347.025986] [filter] (tty/ttyUSB9): port filtered: device is blocklisted Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.929631] [ttyUSB10] port contents loaded: Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931019] [ttyUSB10] bus: usb Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931115] [ttyUSB10] interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.3/1-1.4.3:1.0 Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931149] [ttyUSB10] device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.3 Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931177] [ttyUSB10] driver: ftdi_sio Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931203] [ttyUSB10] vendor: 0403 Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931230] [ttyUSB10] product: 6015 Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931257] [ttyUSB10] revision: 1000 Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931291] [base-manager] adding port ttyUSB10 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.3/1-1.4.3:1.0/ttyUSB10/tty/ttyUSB10 Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: [1756301349.931641] [filter] (tty/ttyUSB10): port filtered: device is blocklisted

12

What is hanging, also is it possible to get the output into a pastebin?

13

What is hanging is the recognition of my 8th devices, which should be ignored. The behavior is each newly added Thiopoly device the recognition of it slows down incrementally until the final 8th is never even recognized…I mean the device never even appears under /dev/ period. Modem manager not running resolves this.

14

How do I do a “pastebin”?

15 
type or paste code here
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "none"
                                                <<<<<<   transaction = 7
                                                <<<<<<   tlv_length  = 0
                                                <<<<<<   message     = "Get Card Status" (0x002F)
Aug 27 13:26:20 thio-demo-0 ModemManager[1706]: <debug> [1756301180.413998] [/dev/cdc-wdm0] received message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 56
                                                <<<<<<   data   = 01:37:00:80:0B:02:02:07:00:2F:00:2B...
Aug 27 13:26:20 thio-demo-0 ModemManager[1706]: <debug> [1756301180.414248] [/dev/cdc-wdm0] received generic response (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 55
                                                <<<<<<   flags   = 0x80
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "response"
                                                <<<<<<   transaction = 7
                                                <<<<<<   tlv_length  = 43
                                                <<<<<<   message     = "Get Card Status" (0x002F)
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Result" (0x02)
                                                <<<<<<   length     = 4
                                                <<<<<<   value      = 00:00:00:00
                                                <<<<<<   translated = SUCCESS
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x16
                                                <<<<<<   length = 5
                                                <<<<<<   value  = 01:00:00:00:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x15
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x11
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Card Status" (0x10)
                                                <<<<<<   length     = 15
                                                <<<<<<   value      = FF:FF:FF:FF:FF:FF:FF:FF:01:02:00:00:00:03:00
                                                <<<<<<   translated = [ index_gw_primary = '65535' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
Aug 27 13:26:20 thio-demo-0 ModemManager[1706]: <debug> [1756301180.414464] [modem0] couldn't check if unlock required: QMI operation failed: No card found (retry)
Aug 27 13:26:20 thio-demo-0 ModemManager[1706]: <debug> [1756301180.414528] [modem0] retrying (3) unlock required check
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.376834] [modem0] loading unlock required (UIM)...
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.377065] [/dev/cdc-wdm0] sent message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 13
                                                <<<<<<   data   = 01:0C:00:00:0B:02:00:08:00:2F:00:00...
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.377128] [/dev/cdc-wdm0] sent generic request (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 12
                                                <<<<<<   flags   = 0x00
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "none"
                                                <<<<<<   transaction = 8
                                                <<<<<<   tlv_length  = 0
                                                <<<<<<   message     = "Get Card Status" (0x002F)
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.397500] [/dev/cdc-wdm0] received message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 56
                                                <<<<<<   data   = 01:37:00:80:0B:02:02:08:00:2F:00:2B...
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.397679] [/dev/cdc-wdm0] received generic response (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 55
                                                <<<<<<   flags   = 0x80
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "response"
                                                <<<<<<   transaction = 8
                                                <<<<<<   tlv_length  = 43
                                                <<<<<<   message     = "Get Card Status" (0x002F)
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Result" (0x02)
                                                <<<<<<   length     = 4
                                                <<<<<<   value      = 00:00:00:00
                                                <<<<<<   translated = SUCCESS
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x16
                                                <<<<<<   length = 5
                                                <<<<<<   value  = 01:00:00:00:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x15
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x11
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Card Status" (0x10)
                                                <<<<<<   length     = 15
                                                <<<<<<   value      = FF:FF:FF:FF:FF:FF:FF:FF:01:02:00:00:00:03:00
                                                <<<<<<   translated = [ index_gw_primary = '65535' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.397881] [modem0] couldn't check if unlock required: QMI operation failed: No card found (retry)
Aug 27 13:26:22 thio-demo-0 ModemManager[1706]: <debug> [1756301182.398005] [modem0] retrying (4) unlock required check
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.376626] [modem0] loading unlock required (UIM)...
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.377020] [/dev/cdc-wdm0] sent message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 13
                                                <<<<<<   data   = 01:0C:00:00:0B:02:00:09:00:2F:00:00...
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.377173] [/dev/cdc-wdm0] sent generic request (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 12
                                                <<<<<<   flags   = 0x00
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "none"
                                                <<<<<<   transaction = 9
                                                <<<<<<   tlv_length  = 0
                                                <<<<<<   message     = "Get Card Status" (0x002F)
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.413276] [/dev/cdc-wdm0] received message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 56
                                                <<<<<<   data   = 01:37:00:80:0B:02:02:09:00:2F:00:2B...
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.413475] [/dev/cdc-wdm0] received generic response (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 55
                                                <<<<<<   flags   = 0x80
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "response"
                                                <<<<<<   transaction = 9
                                                <<<<<<   tlv_length  = 43
                                                <<<<<<   message     = "Get Card Status" (0x002F)
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Result" (0x02)
                                                <<<<<<   length     = 4
                                                <<<<<<   value      = 00:00:00:00
                                                <<<<<<   translated = SUCCESS
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x16
                                                <<<<<<   length = 5
                                                <<<<<<   value  = 01:00:00:00:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x15
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x11
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Card Status" (0x10)
                                                <<<<<<   length     = 15
                                                <<<<<<   value      = FF:FF:FF:FF:FF:FF:FF:FF:01:02:00:00:00:03:00
                                                <<<<<<   translated = [ index_gw_primary = '65535' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.413679] [modem0] couldn't check if unlock required: QMI operation failed: No card found (retry)
Aug 27 13:26:24 thio-demo-0 ModemManager[1706]: <debug> [1756301184.413744] [modem0] retrying (5) unlock required check
Aug 27 13:26:26 thio-demo-0 ModemManager[1706]: <debug> [1756301186.376986] [modem0] loading unlock required (UIM)...
Aug 27 13:26:26 thio-demo-0 ModemManager[1706]: <debug> [1756301186.377171] [/dev/cdc-wdm0] sent message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 13
                                                <<<<<<   data   = 01:0C:00:00:0B:02:00:0A:00:2F:00:00...
Aug 27 13:26:26 thio-demo-0 ModemManager[1706]: <debug> [1756301186.377227] [/dev/cdc-wdm0] sent generic request (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 12
                                                <<<<<<   flags   = 0x00
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "none"
                                                <<<<<<   transaction = 10
                                                <<<<<<   tlv_length  = 0
                                                <<<<<<   message     = "Get Card Status" (0x002F)
Aug 27 13:27:04 thio-demo-0 ModemManager[1706]: <debug> [1756301224.971692] [/dev/cdc-wdm0] received message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 56
                                                <<<<<<   data   = 01:37:00:80:0B:02:02:0A:00:2F:00:2B...
Aug 27 13:27:04 thio-demo-0 ModemManager[1706]: <debug> [1756301224.971845] [/dev/cdc-wdm0] received generic response (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 55
                                                <<<<<<   flags   = 0x80
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "response"
                                                <<<<<<   transaction = 10
                                                <<<<<<   tlv_length  = 43
                                                <<<<<<   message     = "Get Card Status" (0x002F)
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Result" (0x02)
                                                <<<<<<   length     = 4
                                                <<<<<<   value      = 00:00:00:00
                                                <<<<<<   translated = SUCCESS
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x16
                                                <<<<<<   length = 5
                                                <<<<<<   value  = 01:00:00:00:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x15
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x11
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Card Status" (0x10)
                                                <<<<<<   length     = 15
                                                <<<<<<   value      = FF:FF:FF:FF:FF:FF:FF:FF:01:02:00:00:00:03:00
                                                <<<<<<   translated = [ index_gw_primary = '65535' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
Aug 27 13:27:04 thio-demo-0 ModemManager[1706]: <debug> [1756301224.971997] [modem0] couldn't check if unlock required: QMI operation failed: No card found (retry)
Aug 27 13:27:04 thio-demo-0 ModemManager[1706]: <debug> [1756301224.972042] [modem0] retrying (6) unlock required check
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.951248] [modem0] loading unlock required (UIM)...
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.951405] [/dev/cdc-wdm0] sent message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 13
                                                <<<<<<   data   = 01:0C:00:00:0B:02:00:0B:00:2F:00:00...
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.951454] [/dev/cdc-wdm0] sent generic request (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 12
                                                <<<<<<   flags   = 0x00
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "none"
                                                <<<<<<   transaction = 11
                                                <<<<<<   tlv_length  = 0
                                                <<<<<<   message     = "Get Card Status" (0x002F)
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.987648] [/dev/cdc-wdm0] received message...
                                                <<<<<< RAW:
                                                <<<<<<   length = 56
                                                <<<<<<   data   = 01:37:00:80:0B:02:02:0B:00:2F:00:2B...
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.987859] [/dev/cdc-wdm0] received generic response (translated)...
                                                <<<<<< QMUX:
                                                <<<<<<   length  = 55
                                                <<<<<<   flags   = 0x80
                                                <<<<<<   service = "uim"
                                                <<<<<<   client  = 2
                                                <<<<<< QMI:
                                                <<<<<<   flags       = "response"
                                                <<<<<<   transaction = 11
                                                <<<<<<   tlv_length  = 43
                                                <<<<<<   message     = "Get Card Status" (0x002F)
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Result" (0x02)
                                                <<<<<<   length     = 4
                                                <<<<<<   value      = 00:00:00:00
                                                <<<<<<   translated = SUCCESS
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x16
                                                <<<<<<   length = 5
                                                <<<<<<   value  = 01:00:00:00:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x15
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type   = 0x11
                                                <<<<<<   length = 2
                                                <<<<<<   value  = 01:00
                                                <<<<<< TLV:
                                                <<<<<<   type       = "Card Status" (0x10)
                                                <<<<<<   length     = 15
                                                <<<<<<   value      = FF:FF:FF:FF:FF:FF:FF:FF:01:02:00:00:00:03:00
                                                <<<<<<   translated = [ index_gw_primary = '65535' index_1x_primary = '65535' index_gw_secondary = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.988078] [modem0] couldn't check if unlock required: QMI operation failed: GW primary session index unknown
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <info>  [1756301226.988253] [modem0] state changed (unknown -> locked)
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <warn>  [1756301226.988700] [modem0] modem couldn't be initialized: Couldn't check unlock status: QMI operation failed: GW primary session index unknown
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <info>  [1756301226.988799] [modem0] state changed (locked -> failed)
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.989357] [modem0] Voice capabilities supported
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.990088] [ttyUSB4/at] device open count is 3 (open)
Aug 27 13:27:06 thio-demo-0 ModemManager[1706]: <debug> [1756301226.990323] [ttyUSB4/at] --> 'AT+CGMR<CR>'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.002828] [ttyUSB4/at] <-- '<CR><LF>EG25GGBR07A08M2G<CR><LF><CR><LF>OK<CR><LF>'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.003128] [modem0] (quectel) revision EG25GGBR07A08M2G converted to modem name EG25GGB
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.003220] [ttyUSB4/at] device open count is 4 (open)
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.003304] [ttyUSB4/at] device open count is 3 (close)
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.003448] [ttyUSB4/at] --> 'AT+QFASTBOOT=?<CR>'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.022909] [ttyUSB4/at] <-- '<CR><LF>OK<CR><LF>'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.023162] [ttyUSB4/at] device open count is 4 (open)
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.023246] [ttyUSB4/at] device open count is 3 (close)
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.023358] [ttyUSB4/at] --> 'AT+QGMR?<CR>'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.038878] [ttyUSB4/at] <-- '<CR><LF>EG25GGBR07A08M2G_30.203.30.203<CR><LF><CR><LF>OK<CR><LF>'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <warn>  [1756301227.039444] [modem0] error initializing: Modem in failed state: sim-missing
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.040621] [device /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.1]  exported modem at path '/org/freedesktop/ModemManager1/Modem/0'
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.040722] [device /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.1]     plugin:  quectel
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.040756] [device /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.1]     vid:pid: 0x2C7C:0x0125
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.040816] [ttyUSB4/at] device open count is 2 (close)
Aug 27 13:27:07 thio-demo-0 ModemManager[1706]: <debug> [1756301227.040884] [ttyUSB4/at] device open count is 1 (close)
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.706229] [ttyUSB5] port contents loaded:
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.709933] [ttyUSB5]   bus: usb
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710039] [ttyUSB5]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.3/1-1.5.3:1.0
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710077] [ttyUSB5]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.3
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710106] [ttyUSB5]   driver: ftdi_sio
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710132] [ttyUSB5]   vendor: 0403
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710159] [ttyUSB5]   product: 6015
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710187] [ttyUSB5]   revision: 1000
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710222] [base-manager] adding port ttyUSB5 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.3/1-1.5.3:1.0/ttyUSB5/tty/ttyUSB5
Aug 27 13:28:52 thio-demo-0 ModemManager[1706]: <debug> [1756301332.710605] [filter] (tty/ttyUSB5): port filtered: device is blocklisted
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.868266] [ttyUSB6] port contents loaded:
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870374] [ttyUSB6]   bus: usb
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870492] [ttyUSB6]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.1/1-1.5.1:1.0
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870538] [ttyUSB6]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.1
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870575] [ttyUSB6]   driver: ftdi_sio
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870612] [ttyUSB6]   vendor: 0403
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870647] [ttyUSB6]   product: 6015
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870684] [ttyUSB6]   revision: 1000
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.870726] [base-manager] adding port ttyUSB6 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.1/1-1.5.1:1.0/ttyUSB6/tty/ttyUSB6
Aug 27 13:28:58 thio-demo-0 ModemManager[1706]: <debug> [1756301338.871155] [filter] (tty/ttyUSB6): port filtered: device is blocklisted
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.666164] [ttyUSB7] port contents loaded:
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.666892] [ttyUSB7]   bus: usb
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.666979] [ttyUSB7]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.5/1-1.5.5:1.0
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667016] [ttyUSB7]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.5
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667044] [ttyUSB7]   driver: ftdi_sio
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667071] [ttyUSB7]   vendor: 0403
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667098] [ttyUSB7]   product: 6015
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667128] [ttyUSB7]   revision: 1000
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667162] [base-manager] adding port ttyUSB7 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.5/1-1.5.5:1.0/ttyUSB7/tty/ttyUSB7
Aug 27 13:29:01 thio-demo-0 ModemManager[1706]: <debug> [1756301341.667558] [filter] (tty/ttyUSB7): port filtered: device is blocklisted
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.929966] [ttyUSB8] port contents loaded:
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931482] [ttyUSB8]   bus: usb
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931619] [ttyUSB8]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931657] [ttyUSB8]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931685] [ttyUSB8]   driver: ftdi_sio
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931712] [ttyUSB8]   vendor: 0403
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931739] [ttyUSB8]   product: 6015
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931767] [ttyUSB8]   revision: 1000
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.931804] [base-manager] adding port ttyUSB8 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0/ttyUSB8/tty/ttyUSB8
Aug 27 13:29:03 thio-demo-0 ModemManager[1706]: <debug> [1756301343.932198] [filter] (tty/ttyUSB8): port filtered: device is blocklisted
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.023891] [ttyUSB9] port contents loaded:
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025261] [ttyUSB9]   bus: usb
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025377] [ttyUSB9]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.2/1-1.2:1.0
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025411] [ttyUSB9]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.2
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025442] [ttyUSB9]   driver: ftdi_sio
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025469] [ttyUSB9]   vendor: 0403
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025496] [ttyUSB9]   product: 6015
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025527] [ttyUSB9]   revision: 1000
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025563] [base-manager] adding port ttyUSB9 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/ttyUSB9/tty/ttyUSB9
Aug 27 13:29:07 thio-demo-0 ModemManager[1706]: <debug> [1756301347.025986] [filter] (tty/ttyUSB9): port filtered: device is blocklisted
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.929631] [ttyUSB10] port contents loaded:
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931019] [ttyUSB10]   bus: usb
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931115] [ttyUSB10]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.3/1-1.4.3:1.0
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931149] [ttyUSB10]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.3
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931177] [ttyUSB10]   driver: ftdi_sio
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931203] [ttyUSB10]   vendor: 0403
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931230] [ttyUSB10]   product: 6015
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931257] [ttyUSB10]   revision: 1000
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931291] [base-manager] adding port ttyUSB10 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.3/1-1.4.3:1.0/ttyUSB10/tty/ttyUSB10
Aug 27 13:29:09 thio-demo-0 ModemManager[1706]: <debug> [1756301349.931641] [filter] (tty/ttyUSB10): port filtered: device is blocklisted
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.390834] [ttyUSB0] port contents loaded:
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.390936] [ttyUSB0]   bus: usb
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.390968] [ttyUSB0]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.6/1-1.5.6:1.0
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391000] [ttyUSB0]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.6
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391029] [ttyUSB0]   driver: ftdi_sio
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391056] [ttyUSB0]   vendor: 0403
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391086] [ttyUSB0]   product: 6015
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391113] [ttyUSB0]   revision: 1000
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391157] [base-manager] adding port ttyUSB0 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.6/1-1.5.6:1.0/ttyUSB0/tty/ttyUSB0
Aug 27 13:36:49 thio-demo-0 ModemManager[1706]: <debug> [1756301809.391497] [filter] (tty/ttyUSB0): port filtered: device is blocklisted
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.725238] [ttyUSB5] port contents loaded:
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726544] [ttyUSB5]   bus: usb
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726630] [ttyUSB5]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.4/1-1.5.4:1.0
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726673] [ttyUSB5]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.4
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726709] [ttyUSB5]   driver: ftdi_sio
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726743] [ttyUSB5]   vendor: 0403
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726781] [ttyUSB5]   product: 6015
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726816] [ttyUSB5]   revision: 1000
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.726856] [base-manager] adding port ttyUSB5 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.4/1-1.5.4:1.0/ttyUSB5/tty/ttyUSB5
Aug 27 13:37:29 thio-demo-0 ModemManager[1706]: <debug> [1756301849.727602] [filter] (tty/ttyUSB5): port filtered: device is blocklisted
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.534664] [ttyUSB6] port contents loaded:
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.540643] [ttyUSB6]   bus: usb
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541213] [ttyUSB6]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.2/1-1.5.2:1.0
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541294] [ttyUSB6]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.2
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541331] [ttyUSB6]   driver: ftdi_sio
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541364] [ttyUSB6]   vendor: 0403
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541396] [ttyUSB6]   product: 6015
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541426] [ttyUSB6]   revision: 1000
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541462] [base-manager] adding port ttyUSB6 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.2/1-1.5.2:1.0/ttyUSB6/tty/ttyUSB6
Aug 27 13:38:04 thio-demo-0 ModemManager[1706]: <debug> [1756301884.541839] [filter] (tty/ttyUSB6): port filtered: device is blocklisted
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608440] [ttyUSB7] port contents loaded:
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608673] [ttyUSB7]   bus: usb
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608714] [ttyUSB7]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.7/1-1.4.7:1.0
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608756] [ttyUSB7]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.7
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608793] [ttyUSB7]   driver: ftdi_sio
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608827] [ttyUSB7]   vendor: 0403
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608864] [ttyUSB7]   product: 6015
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608899] [ttyUSB7]   revision: 1000
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.608939] [base-manager] adding port ttyUSB7 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.7/1-1.4.7:1.0/ttyUSB7/tty/ttyUSB7
Aug 27 13:38:08 thio-demo-0 ModemManager[1706]: <debug> [1756301888.609363] [filter] (tty/ttyUSB7): port filtered: device is blocklisted
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457472] [ttyUSB8] port contents loaded:
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457630] [ttyUSB8]   bus: usb
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457678] [ttyUSB8]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.5/1-1.4.5:1.0
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457721] [ttyUSB8]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.5
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457765] [ttyUSB8]   driver: ftdi_sio
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457802] [ttyUSB8]   vendor: 0403
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457841] [ttyUSB8]   product: 6015
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457880] [ttyUSB8]   revision: 1000
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.457924] [base-manager] adding port ttyUSB8 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.5/1-1.4.5:1.0/ttyUSB8/tty/ttyUSB8
Aug 27 13:38:28 thio-demo-0 ModemManager[1706]: <debug> [1756301908.458370] [filter] (tty/ttyUSB8): port filtered: device is blocklisted
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.711172] [ttyUSB9] port contents loaded:
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713156] [ttyUSB9]   bus: usb
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713261] [ttyUSB9]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4/1-1.4.4:1.0
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713304] [ttyUSB9]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713343] [ttyUSB9]   driver: ftdi_sio
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713381] [ttyUSB9]   vendor: 0403
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713416] [ttyUSB9]   product: 6015
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713468] [ttyUSB9]   revision: 1000
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713512] [base-manager] adding port ttyUSB9 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.4/1-1.4.4:1.0/ttyUSB9/tty/ttyUSB9
Aug 27 13:38:33 thio-demo-0 ModemManager[1706]: <debug> [1756301913.713971] [filter] (tty/ttyUSB9): port filtered: device is blocklisted
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.014799] [ttyUSB10] port contents loaded:
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020385] [ttyUSB10]   bus: usb
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020520] [ttyUSB10]   interface: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020556] [ttyUSB10]   device: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020586] [ttyUSB10]   driver: ftdi_sio
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020614] [ttyUSB10]   vendor: 0403
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020642] [ttyUSB10]   product: 6015
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020675] [ttyUSB10]   revision: 1000
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.020710] [base-manager] adding port ttyUSB10 at sysfs path: /sys/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0/ttyUSB10/tty/ttyUSB10
Aug 27 13:38:52 thio-demo-0 ModemManager[1706]: <debug> [1756301932.021122] [filter] (tty/ttyUSB10): port filtered: device is blocklisted
16

ttyUSB10 is the 7th device added. The next would have been ttyUSB11 but it never shows up. Bringing modem manager down resolves this. Make sense?

17

Okay so indeed modem-manager is acting suspeciously here. - Is it possible to get the full journalctl -b output into a file and upload it somewhere?

On a side note: looking at the source code as well there is quite a difference in behaviour when ID_MM_CANDIDATE=1 is set for the device as well, I wonder if we should try to make the udev rule set this to =0 as well as the ignore flag just to test if that makes things better.

18

Here is a link. Let me know if this works. In the mean time I will try the CANDIDATE idea.

https://drive.google.com/file/d/1toiASiv8Q3yg5n23V2idu12zXN8kNo9E/view?usp=sharing

19

Same behavior with:

ATTRS{idVendor}==“0403”, ATTRS{idProduct}==“6015”, ENV{ID_MM_DEVICE_IGNORE}=“1”, ENV{ID_MM_CANDIDATE}=“0”

20 
(polling-loop) root@thio-demo-0:/var/snap/thio/current/ggc_user# udevadm info /dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6RU-if00-port0 
P: /devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.5/1-1.5.5:1.0/ttyUSB4/tty/ttyUSB4
N: ttyUSB4
L: 0
S: serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6RU-if00-port0
S: serial/by-path/platform-fe980000.usb-usb-0:1.5.5:1.0-port0
E: DEVPATH=/devices/platform/soc/fe980000.usb/usb1/1-1/1-1.5/1-1.5.5/1-1.5.5:1.0/ttyUSB4/tty/ttyUSB4
E: DEVNAME=/dev/ttyUSB4
E: MAJOR=188
E: MINOR=4
E: SUBSYSTEM=tty
E: USEC_INITIALIZED=164607327
E: ID_VENDOR=Thiopoly
E: ID_VENDOR_ENC=Thiopoly
E: ID_VENDOR_ID=0403
E: ID_MODEL=FT230X_Basic_UART
E: ID_MODEL_ENC=FT230X\x20Basic\x20UART
E: ID_MODEL_ID=6015
E: ID_REVISION=1000
E: ID_SERIAL=Thiopoly_FT230X_Basic_UART_DU0DO6RU
E: ID_SERIAL_SHORT=DU0DO6RU
E: ID_TYPE=generic
E: ID_BUS=usb
E: ID_USB_INTERFACES=:ffffff:
E: ID_USB_INTERFACE_NUM=00
E: ID_USB_DRIVER=ftdi_sio
E: ID_VENDOR_FROM_DATABASE=Future Technology Devices International, Ltd
E: ID_MODEL_FROM_DATABASE=Bridge(I2C/SPI/UART/FIFO)
E: ID_PATH=platform-fe980000.usb-usb-0:1.5.5:1.0
E: ID_PATH_TAG=platform-fe980000_usb-usb-0_1_5_5_1_0
E: ID_MM_CANDIDATE=0
E: ID_MM_DEVICE_IGNORE=1
E: DEVLINKS=/dev/serial/by-id/usb-Thiopoly_FT230X_Basic_UART_DU0DO6RU-if00-port0 /dev/serial/by-path/platform-fe980000.usb-usb-0:1.5.5:1.0-port0
E: TAGS=:snap_modem-manager_qmicli:snap_modem-manager_modemmanager:snap_network-manager_networkmanager:snap_modem-manager_qmi-network:snap_modem-manager_mbim-network:snap_modem-manager_mbimcli:systemd:
E: CURRENT_TAGS=:snap_modem-manager_qmicli:snap_modem-manager_modemmanager:snap_network-manager_networkmanager:snap_modem-manager_qmi-network:snap_modem-manager_mbim-network:snap_modem-manager_mbimcli:systemd: