I'm new here on the list. First of all, we thank you guys for the great effort on making this application which has really changed the way people use Linux in general and making this OS a lot more easier to use for the general population.
We're using Network Manager via Python DBus (like many others) to see and use modem devices.
The problem is, we connect modems just fine, and everything works. But when we plug in new USB devices, the existing modems that have already been connected all disconnect at the same exact time. If we just plug all USB dongles and after that connect them, it will have no problem.
1. Connect USB dongle/broadband on any USB port.
2. Configure and get the dongle to connect successfully
3. Repeat from step #1 with more USB dongles, but one by one
4. Previous modem(s) will disconnect at the same exact time shortly after the later is connected
There is no kernel error during these steps, so dmesg just prints normal ttyUSB* population, as well as SCSI drive information if the dongle has one. Bug reproduction has been found to happen with as low as 2 USB dongles, but sometimes 3 is necessary to reproduce the bug.
I am on Ubuntu 12.04 LTS, and also have tried 13.04 as well as 10.04... they all act the same. So, I figured this is Network Manager-related since no kernel errors are ever observed. And this happens on VirtualBox (Windows, Linux), VMware Workstation (Windows, Linux) / Fusion (Mac) as well as on non-virtual full OS running on bare metal, multiple computers; practically on any platform.
May 31 07:20:24 Dev-Machine kernel: [88206.875032] usb 1-1: new high-speed USB device number 6 using ehci_hcd
May 31 07:20:24 Dev-Machine kernel: [88207.105755] scsi45 : usb-storage 1-1:1.0
May 31 07:20:24 Dev-Machine kernel: [88207.218185] scsi46 : usb-storage 1-1:1.1
May 31 07:20:24 Dev-Machine mtp-probe: checking bus 1, device 6: "/sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-1"
May 31 07:20:24 Dev-Machine mtp-probe: bus: 1, device: 6 was not an MTP device
May 31 07:20:25 Dev-Machine kernel: [88208.197569] scsi 45:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
May 31 07:20:25 Dev-Machine kernel: [88208.333719] scsi 46:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
May 31 07:20:25 Dev-Machine kernel: [88208.398410] sr1: scsi-1 drive
May 31 07:20:25 Dev-Machine kernel: [88208.416334] sr 45:0:0:0: Attached scsi CD-ROM sr1
May 31 07:20:25 Dev-Machine kernel: [88208.446453] sr 45:0:0:0: Attached scsi generic sg2 type 5
May 31 07:20:25 Dev-Machine kernel: [88208.490757] sd 46:0:0:0: Attached scsi generic sg3 type 0
May 31 07:20:26 Dev-Machine kernel: [88208.801853] sd 46:0:0:0: [sdb] Attached SCSI removable disk
May 31 07:20:28 Dev-Machine usb_modeswitch: switching device 12d1:1446 on 001/006
May 31 07:20:28 Dev-Machine kernel: [88211.112869] usb 1-1: USB disconnect, device number 6
May 31 07:20:28 Dev-Machine udevd[18532]: inotify_add_watch(6, /dev/sdb, 10) failed: No such file or directory
May 31 07:20:35 Dev-Machine kernel: [88217.824968] usb 1-1: new high-speed USB device number 7 using ehci_hcd
May 31 07:20:35 Dev-Machine mtp-probe: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-1"
May 31 07:20:35 Dev-Machine kernel: [88218.381039] option 1-1:1.0: GSM modem (1-port) converter detected
May 31 07:20:35 Dev-Machine kernel: [88218.381593] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
May 31 07:20:35 Dev-Machine kernel: [88218.381731] option 1-1:1.1: GSM modem (1-port) converter detected
May 31 07:20:35 Dev-Machine kernel: [88218.381827] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
May 31 07:20:35 Dev-Machine kernel: [88218.382679] scsi49 : usb-storage 1-1:1.2
May 31 07:20:35 Dev-Machine mtp-probe: bus: 1, device: 7 was not an MTP device
May 31 07:20:36 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) opening serial port...
May 31 07:20:36 Dev-Machine kernel: [88219.387561] scsi 49:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
May 31 07:20:36 Dev-Machine kernel: [88219.388538] sd 49:0:0:0: Attached scsi generic sg2 type 0
May 31 07:20:36 Dev-Machine kernel: [88219.414940] sd 49:0:0:0: [sdb] Attached SCSI removable disk
May 31 07:20:36 Dev-Machine usb_modeswitch: switched to 12d1:141b on 001/006
May 31 07:20:37 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) closing serial port...
May 31 07:20:37 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) serial port closed
May 31 07:20:37 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) opening serial port...
May 31 07:20:37 Dev-Machine modem-manager[1290]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-1 claimed port ttyUSB0
May 31 07:20:37 Dev-Machine usb_modeswitch[18511]: usb_modeswitch: switched to 12d1:141b on 1/7
May 31 07:20:37 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) closing serial port...
May 31 07:20:37 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) serial port closed
May 31 07:20:39 Dev-Machine modem-manager[1290]: <info> (ttyUSB1) opening serial port...
May 31 07:20:39 Dev-Machine modem-manager[1290]: <info> (ttyUSB1) closing serial port...
May 31 07:20:40 Dev-Machine modem-manager[1290]: <info> (ttyUSB1) serial port closed
May 31 07:20:40 Dev-Machine modem-manager[1290]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-1 claimed port ttyUSB1
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <warn> (ttyUSB0): failed to look up interface index
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/8
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): now managed
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
May 31 07:20:40 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
May 31 07:20:40 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
May 31 07:20:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
---------------- First device is connected using the Gnome User interface here, but it usually fails the first time, so I retried and it got connected
May 31 07:21:40 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) starting connection 'OrangeNet connection'
May 31 07:21:40 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
May 31 07:21:40 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
May 31 07:21:40 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
May 31 07:21:40 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
May 31 07:21:40 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) opening serial port...
May 31 07:21:40 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (disabled -> enabling)
May 31 07:21:40 Dev-Machine modem-manager[1290]: <info> (ttyUSB1) opening serial port...
May 31 07:21:41 Dev-Machine modem-manager[1290]: <info> (ttyUSB0): using text mode for SMS
May 31 07:21:41 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (enabling -> enabled)
May 31 07:21:41 Dev-Machine NetworkManager[1303]: <info> WWAN now enabled by management service
May 31 07:21:41 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (enabled -> registered)
May 31 07:21:42 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (registered -> connecting)
May 31 07:21:42 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (connecting -> registered)
May 31 07:21:42 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (registered -> searching)
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <warn> GSM connection failed: (32) No cause information available
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'modem-no-carrier') [40 120 25]
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <warn> Activation (ttyUSB0) failed.
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
May 31 07:21:42 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
May 31 07:21:42 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
May 31 07:21:42 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (searching -> registered)
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
May 31 07:21:42 Dev-Machine NetworkManager[1303]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
May 31 07:21:53 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) starting connection 'OrangeNet connection'
May 31 07:21:53 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
May 31 07:21:53 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
May 31 07:21:53 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
May 31 07:21:53 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
May 31 07:21:54 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (registered -> connecting)
May 31 07:21:54 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (connecting -> connected)
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> starting PPP connection
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> pppd started with pid 18657
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) started...
May 31 07:21:54 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
May 31 07:21:54 Dev-Machine pppd[18657]: Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
May 31 07:21:54 Dev-Machine pppd[18657]: pppd 2.4.5 started by root, uid 0
May 31 07:21:54 Dev-Machine pppd[18657]: Using interface ppp0
May 31 07:21:54 Dev-Machine pppd[18657]: Connect: ppp0 <--> /dev/ttyUSB0
May 31 07:21:54 Dev-Machine NetworkManager[1303]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
May 31 07:21:54 Dev-Machine NetworkManager[1303]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
May 31 07:21:54 Dev-Machine pppd[18657]: CHAP authentication succeeded
May 31 07:21:54 Dev-Machine pppd[18657]: CHAP authentication succeeded
May 31 07:21:57 Dev-Machine pppd[18657]: Could not determine remote IP address: defaulting to 10.64.64.64
May 31 07:21:57 Dev-Machine pppd[18657]: local IP address 41.74.216.8
May 31 07:21:57 Dev-Machine pppd[18657]: remote IP address 10.64.64.64
May 31 07:21:57 Dev-Machine pppd[18657]: primary DNS address 80.12.72.7
May 31 07:21:57 Dev-Machine pppd[18657]: secondary DNS address 41.190.236.206
May 31 07:21:57 Dev-Machine NetworkManager[1303]: <info> PPP manager(IP Config Get) reply received.
May 31 07:21:57 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
May 31 07:21:57 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) started...
May 31 07:21:58 Dev-Machine NetworkManager[1303]: <info> DNS: starting dnsmasq...
May 31 07:21:58 Dev-Machine NetworkManager[1303]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
May 31 07:21:58 Dev-Machine dnsmasq[16662]: exiting on receipt of SIGTERM
May 31 07:21:59 Dev-Machine dnsmasq[18672]: started, version 2.59 cache disabled
May 31 07:21:59 Dev-Machine dnsmasq[18672]: compile time options: IPv6 GNU-getopt DBus i18n DHCP TFTP conntrack IDN
May 31 07:21:59 Dev-Machine dnsmasq[18672]: using nameserver 41.190.236.206#53
May 31 07:21:59 Dev-Machine dnsmasq[18672]: using nameserver 80.12.72.7#53
May 31 07:21:59 Dev-Machine dnsmasq[18672]: using nameserver 192.168.2.1#53
May 31 07:21:59 Dev-Machine NetworkManager[1303]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
May 31 07:21:59 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: ip-config -> activated (reason 'none') [70 100 0]
May 31 07:21:59 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) successful, device activated.
May 31 07:21:59 Dev-Machine NetworkManager[1303]: <info> Activation (ttyUSB0) Stage 5 of 5 (IPv4 Commit) complete.
May 31 07:21:59 Dev-Machine dbus[757]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
May 31 07:22:00 Dev-Machine dbus[757]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 31 07:22:14 Dev-Machine ntpdate[18719]: step time server 91.189.94.4 offset 2.032579 sec
---------------- Second modem is plugged into the USB port here
May 31 07:22:57 Dev-Machine kernel: [88358.526073] usb 1-2: new high-speed USB device number 8 using ehci_hcd
May 31 07:22:58 Dev-Machine kernel: [88358.697687] scsi50 : usb-storage 1-2:1.0
May 31 07:22:58 Dev-Machine kernel: [88358.703595] scsi51 : usb-storage 1-2:1.1
May 31 07:22:58 Dev-Machine mtp-probe: checking bus 1, device 8: "/sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-2"
May 31 07:22:58 Dev-Machine mtp-probe: bus: 1, device: 8 was not an MTP device
May 31 07:22:59 Dev-Machine kernel: [88359.709122] scsi 50:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
May 31 07:22:59 Dev-Machine kernel: [88359.712344] scsi 51:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
May 31 07:22:59 Dev-Machine kernel: [88359.778224] sr1: scsi-1 drive
May 31 07:22:59 Dev-Machine kernel: [88359.778637] sr 50:0:0:0: Attached scsi CD-ROM sr1
May 31 07:22:59 Dev-Machine kernel: [88359.778864] sr 50:0:0:0: Attached scsi generic sg3 type 5
May 31 07:22:59 Dev-Machine kernel: [88359.779267] sd 51:0:0:0: Attached scsi generic sg4 type 0
May 31 07:22:59 Dev-Machine kernel: [88359.888092] sd 51:0:0:0: [sdc] Attached SCSI removable disk
May 31 07:22:59 Dev-Machine usb_modeswitch: switching device 12d1:1446 on 001/008
May 31 07:23:00 Dev-Machine kernel: [88360.578896] usb 1-2: USB disconnect, device number 8
May 31 07:23:00 Dev-Machine kernel: [88360.582631] scsi 51:0:0:0: rejecting I/O to offline device
May 31 07:23:00 Dev-Machine kernel: [88360.582666] scsi 51:0:0:0: killing request
May 31 07:23:06 Dev-Machine kernel: [88366.738422] usb 1-2: new high-speed USB device number 9 using ehci_hcd
May 31 07:23:06 Dev-Machine kernel: [88366.907552] option 1-2:1.0: GSM modem (1-port) converter detected
May 31 07:23:06 Dev-Machine kernel: [88366.908092] usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2
May 31 07:23:06 Dev-Machine kernel: [88366.908721] option 1-2:1.1: GSM modem (1-port) converter detected
May 31 07:23:06 Dev-Machine kernel: [88366.909611] usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3
May 31 07:23:06 Dev-Machine kernel: [88366.910199] option 1-2:1.2: GSM modem (1-port) converter detected
May 31 07:23:06 Dev-Machine kernel: [88366.911306] usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4
May 31 07:23:06 Dev-Machine kernel: [88366.913067] scsi55 : usb-storage 1-2:1.3
May 31 07:23:06 Dev-Machine mtp-probe: checking bus 1, device 9: "/sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-2"
May 31 07:23:06 Dev-Machine kernel: [88366.915454] scsi56 : usb-storage 1-2:1.4
May 31 07:23:06 Dev-Machine mtp-probe: bus: 1, device: 9 was not an MTP device
May 31 07:23:06 Dev-Machine modem-manager[1290]: <info> (ttyUSB2) opening serial port...
May 31 07:23:07 Dev-Machine kernel: [88367.920952] scsi 55:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
May 31 07:23:07 Dev-Machine kernel: [88367.940466] scsi 56:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
May 31 07:23:07 Dev-Machine modem-manager[1290]: <info> (ttyUSB2) closing serial port...
May 31 07:23:07 Dev-Machine modem-manager[1290]: <info> (ttyUSB2) serial port closed
May 31 07:23:07 Dev-Machine modem-manager[1290]: <info> (ttyUSB2) opening serial port...
May 31 07:23:07 Dev-Machine kernel: [88368.079094] sr1: scsi-1 drive
May 31 07:23:07 Dev-Machine kernel: [88368.079259] sr 55:0:0:0: Attached scsi CD-ROM sr1
May 31 07:23:07 Dev-Machine kernel: [88368.090986] sr 55:0:0:0: Attached scsi generic sg3 type 5
May 31 07:23:07 Dev-Machine kernel: [88368.091363] sd 56:0:0:0: Attached scsi generic sg4 type 0
May 31 07:23:07 Dev-Machine kernel: [88368.384644] sd 56:0:0:0: [sdc] Attached SCSI removable disk
May 31 07:23:08 Dev-Machine kernel: [88369.135762] usb 1-2: usbfs: USBDEVFS_CONTROL failed cmd usb_modeswitch rqt 128 rq 6 len 255 ret -110
May 31 07:23:08 Dev-Machine usb_modeswitch: switched to 12d1:1001 on 001/008
May 31 07:23:08 Dev-Machine modem-manager[1290]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-2 claimed port ttyUSB2
May 31 07:23:09 Dev-Machine modem-manager[1290]: <info> (ttyUSB2) closing serial port...
May 31 07:23:09 Dev-Machine modem-manager[1290]: <info> (ttyUSB2) serial port closed
May 31 07:23:09 Dev-Machine usb_modeswitch[18748]: usb_modeswitch: switched to 12d1:1001 on 1/9
May 31 07:23:09 Dev-Machine modem-manager[1290]: <info> (ttyUSB4) opening serial port...
May 31 07:23:09 Dev-Machine modem-manager[1290]: <info> (ttyUSB3) opening serial port...
May 31 07:23:11 Dev-Machine modem-manager[1290]: <info> (ttyUSB4) closing serial port...
May 31 07:23:11 Dev-Machine modem-manager[1290]: <info> (ttyUSB4) serial port closed
May 31 07:23:11 Dev-Machine modem-manager[1290]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-2 claimed port ttyUSB4
May 31 07:23:17 Dev-Machine modem-manager[1290]: <info> (ttyUSB3) closing serial port...
May 31 07:23:17 Dev-Machine modem-manager[1290]: <info> (ttyUSB3) serial port closed
May 31 07:23:17 Dev-Machine modem-manager[1290]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:11.0/0000:02:02.0/usb1/1-2 claimed port ttyUSB3
---------------- Disconnection of all previously connected modems happens around here...
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <warn> (ttyUSB2): failed to look up interface index
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> WWAN now disabled by management service
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): device state change: activated -> disconnected (reason 'none') [100 30 0]
May 31 07:23:17 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (connected -> disconnecting)
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
May 31 07:23:17 Dev-Machine pppd[18657]: Terminating on signal 15
May 31 07:23:17 Dev-Machine pppd[18657]: Connect time 1.4 minutes.
May 31 07:23:17 Dev-Machine pppd[18657]: Sent 0 bytes, received 0 bytes.
May 31 07:23:17 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
May 31 07:23:17 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
May 31 07:23:17 Dev-Machine pppd[18657]: Connection terminated.
May 31 07:23:17 Dev-Machine avahi-daemon[809]: Withdrawing workstation service for ppp0.
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> DNS: starting dnsmasq...
May 31 07:23:17 Dev-Machine dnsmasq[18672]: exiting on receipt of SIGTERM
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB0): writing resolv.conf to /sbin/resolvconf
May 31 07:23:17 Dev-Machine dnsmasq[18903]: started, version 2.59 cache disabled
May 31 07:23:17 Dev-Machine dnsmasq[18903]: compile time options: IPv6 GNU-getopt DBus i18n DHCP TFTP conntrack IDN
May 31 07:23:17 Dev-Machine dnsmasq[18903]: using nameserver 192.168.2.1#53
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
May 31 07:23:17 Dev-Machine dbus[757]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB2): new GSM/UMTS device (driver: 'option1' ifindex: 0)
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB2): exported as /org/freedesktop/NetworkManager/Devices/9
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB2): now managed
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB2): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
May 31 07:23:17 Dev-Machine pppd[18657]: Exit.
May 31 07:23:17 Dev-Machine dbus[757]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB2): deactivating device (reason 'managed') [2]
May 31 07:23:17 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
May 31 07:23:17 Dev-Machine NetworkManager[1303]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
May 31 07:23:17 Dev-Machine NetworkManager[1303]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> disconnect failed: (32) The device is already being disconnected.
May 31 07:23:17 Dev-Machine NetworkManager[1303]: <info> (ttyUSB2): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (disconnecting -> registered)
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> (ttyUSB1) closing serial port...
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> (ttyUSB1) serial port closed
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (registered -> disabling)
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) closing serial port...
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> (ttyUSB0) serial port closed
May 31 07:23:19 Dev-Machine modem-manager[1290]: <info> Modem /org/freedesktop/ModemManager/Modems/7: state changed (disabling -> disabled)
I hope I have provided as much details required for debugging. Again, we sincerely appreciate the project and all efforts to make it better by the day.
