Hi Aleksander, On 03/03/16 16:24, Aleksander Morgado wrote: > I'm assuming ModemManager runs as root; is there any other program > making use of the proxy here? mbimcli maybe? In order to avoid these > kind of issues, the quickest solution is to always run everything as > root, or under sudo for what it's worth. Maybe mbimcli with a normal > user starts the proxy and MM with uid 0 tries to access it and uids > don't match? > > Another option is to manually run the proxy before MM is started; e.g.: > > [terminal 1] $ sudo /usr/libexec/mbim-proxy --verbose > [terminal 2] $ sudo /usr/sbin/ModemManager --debug
I did that (see attached logs). Everything is run as root. The outcome however is quite the same. I also attached a try with mbimcli after a fresh boot with neither mbim proxy nor ModemManger running. To me the mbimcli output an the mbim-proxy log look quite similar. Any hints what else I could try? Andreas
[03 Mar 2016, 15:45:48] [Debug] creating UNIX socket service... [03 Mar 2016, 15:45:48] [Debug] starting UNIX socket service at 'mbim-proxy'... [03 Mar 2016, 15:46:08] [Debug] Client (6) connection open... [03 Mar 2016, 15:46:08] [Debug] opening device... [03 Mar 2016, 15:46:08] [Debug] [/dev/cdc-wdm0] Queried max control message size: 1024 [03 Mar 2016, 15:46:08] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:01:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:08] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 1 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:09] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:09] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 2 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:10] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:03:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:10] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 3 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:11] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:04:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:11] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 4 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:12] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:05:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:12] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 5 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:13] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:06:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:13] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 6 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:14] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:07:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:14] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 7 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:15] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:08:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:15] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 8 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:16] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:09:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:16] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 9 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:17] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0A:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:17] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 10 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:18] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0B:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:18] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 11 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:19] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0C:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:19] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 12 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:20] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0D:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:20] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 13 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:21] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0E:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:21] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 14 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:22] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0F:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:22] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 15 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:23] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:10:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:23] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 16 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:24] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:11:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:24] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 17 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:25] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:12:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:25] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 18 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:26] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:13:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:26] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 19 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:27] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:14:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:27] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 20 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:28] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:15:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:28] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 21 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:29] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:16:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:29] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 22 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:30] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:17:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:30] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 23 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:31] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:18:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:31] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 24 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:32] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:19:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:32] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 25 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:33] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1A:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:33] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 26 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:34] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1B:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:34] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 27 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:35] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1C:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:35] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 28 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:36] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1D:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:36] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 29 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:37] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1E:00:00:00:00:04:00:00 [03 Mar 2016, 15:46:37] [Debug] [/dev/cdc-wdm0] Sent message (translated).[03 Mar 2016, 15:46:38] -Warning ** error opening device: Transaction timed out .. <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 30 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:46:38] [Debug] Client (6) connection closed... [03 Mar 2016, 15:46:38] [Debug] open operation timed out: closed [03 Mar 2016, 15:47:08] [Debug] exiting 'mbim-proxy'...
# mbimcli -v -d /dev/cdc-wdm0 --query-device-caps > /tmp/mbimcli.log 2>&1 [03 Mar 2016, 15:21:55] [Debug] opening device... [03 Mar 2016, 15:21:55] [Debug] [/dev/cdc-wdm0] Queried max control message size: 1024 [03 Mar 2016, 15:21:55] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:01:00:00:00:00:04:00:00 [03 Mar 2016, 15:21:55] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 1 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:21:56] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:04:00:00 [03 Mar 2016, 15:21:56] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 2 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:21:57] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:03:00:00:00:00:04:00:00 [03 Mar 2016, 15:21:57] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 3 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:21:58] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:04:00:00:00:00:04:00:00 [03 Mar 2016, 15:21:58] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 4 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:21:59] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:05:00:00:00:00:04:00:00 [03 Mar 2016, 15:21:59] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 5 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:00] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:06:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:00] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 6 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:01] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:07:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:01] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 7 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:02] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:08:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:02] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 8 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:03] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:09:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:03] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 9 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:04] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0A:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:04] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 10 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:05] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0B:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:05] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 11 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:06] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0C:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:06] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 12 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:07] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0D:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:07] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 13 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:08] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0E:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:08] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 14 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:09] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:0F:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:09] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 15 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:10] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:10:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:10] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 16 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:11] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:11:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:11] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 17 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:12] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:12:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:12] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 18 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:13] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:13:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:13] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 19 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:14] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:14:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:14] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 20 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:15] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:15:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:15] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 21 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:16] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:16:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:16] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 22 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:17] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:17:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:17] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 23 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:18] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:18:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:18] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 24 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:19] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:19:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:19] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 25 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:20] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1A:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:20] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 26 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:21] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1B:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:21] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 27 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:22] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1C:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:22] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 28 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:23] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1D:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:23] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 29 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:24] [Debug] [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:1E:00:00:00:00:04:00:00 [03 Mar 2016, 15:22:24] [Debug] [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 30 <<<<<< Contents: <<<<<< max_control_transfer = 1024 [03 Mar 2016, 15:22:25] [Debug] open opeerror: couldn't open the MbimDevice: Transaction timed out ration timed out: closed
****************************************************** * * Starting log for modem-manager * Current time: 2016-Mar-03 15:46:07 * ****************************************************** <info> [1457019967.975891] [main.c:136] main(): ModemManager (version 1.4.12) starting in system bus... <debug> [1457019967.989405] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager... <debug> [1457019967.997601] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager' <debug> [1457019968.008441] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE' <debug> [1457019968.008571] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA' <debug> [1457019968.010573] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion' <debug> [1457019968.010689] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic' <debug> [1457019968.010800] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi' <debug> [1457019968.012512] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed' <debug> [1457019968.015631] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei' <debug> [1457019968.017121] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium' <debug> [1457019968.017238] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop' <debug> [1457019968.017324] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer' <debug> [1457019968.019759] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM' <debug> [1457019968.019877] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola' <debug> [1457019968.019994] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK' <debug> [1457019968.022357] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)' <debug> [1457019968.022481] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia' <debug> [1457019968.024975] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE' <debug> [1457019968.025104] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel' <debug> [1457019968.025224] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option' <debug> [1457019968.027520] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech' <debug> [1457019968.027649] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung' <debug> [1457019968.030688] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra' <debug> [1457019968.033029] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech' <debug> [1457019968.033159] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit' <debug> [1457019968.033275] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7' <debug> [1457019968.033397] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom' <debug> [1457019968.036182] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X' <debug> [1457019968.036337] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE' <debug> [1457019968.036346] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins <debug> [1457019968.036746] [main.c:83] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired <debug> [1457019968.036750] [mm-base-manager.c:485] mm_base_manager_start(): Starting automatic device scan... <debug> [1457019968.038121] [mm-base-manager.c:524] mm_base_manager_start(): Finished device scan... <debug> [1457019968.042599] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3] Checking device support... <debug> [1457019968.042643] [mm-device.c:327] mm_device_grab_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) could not get vendor/product ID <debug> [1457019968.042663] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042667] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042669] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers <debug> [1457019968.042670] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers <debug> [1457019968.042672] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042673] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042675] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042693] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags <debug> [1457019968.042695] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042696] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags <debug> [1457019968.042698] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042699] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042701] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042720] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042721] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042724] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042725] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers <debug> [1457019968.042727] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042728] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042730] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042731] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042732] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs <debug> [1457019968.042734] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '5' plugins to try... <debug> [1457019968.042736] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Cinterion' <debug> [1457019968.042737] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Iridium' <debug> [1457019968.042738] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Nokia' <debug> [1457019968.042740] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Via CBP7' <debug> [1457019968.042741] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Generic' <debug> [1457019968.042751] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB0] probe required: 'at, at-vendor' <debug> [1457019968.042756] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-vendor' <debug> [1457019968.042828] [mm-base-manager.c:320] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted <debug> [1457019968.042887] [mm-base-manager.c:320] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted <debug> [1457019968.042929] [mm-base-manager.c:320] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted <debug> [1457019968.042970] [mm-base-manager.c:320] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted <debug> [1457019968.043058] [mm-base-manager.c:296] device_added(): (tty/ptyp0): could not get port's parent device <debug> [1457019968.043085] [mm-base-manager.c:296] device_added(): (tty/ptyp1): could not get port's parent device <debug> [1457019968.043112] [mm-base-manager.c:296] device_added(): (tty/ptyp2): could not get port's parent device <debug> [1457019968.043137] [mm-base-manager.c:296] device_added(): (tty/ptyp3): could not get port's parent device <debug> [1457019968.043163] [mm-base-manager.c:296] device_added(): (tty/ptyp4): could not get port's parent device <debug> [1457019968.043204] [mm-base-manager.c:296] device_added(): (tty/ptyp5): could not get port's parent device <debug> [1457019968.043245] [mm-base-manager.c:296] device_added(): (tty/ptyp6): could not get port's parent device <debug> [1457019968.043271] [mm-base-manager.c:296] device_added(): (tty/ptyp7): could not get port's parent device <debug> [1457019968.043526] [mm-base-manager.c:296] device_added(): (tty/ttyp0): could not get port's parent device <debug> [1457019968.043552] [mm-base-manager.c:296] device_added(): (tty/ttyp1): could not get port's parent device <debug> [1457019968.043579] [mm-base-manager.c:296] device_added(): (tty/ttyp2): could not get port's parent device <debug> [1457019968.043604] [mm-base-manager.c:296] device_added(): (tty/ttyp3): could not get port's parent device <debug> [1457019968.043630] [mm-base-manager.c:296] device_added(): (tty/ttyp4): could not get port's parent device <debug> [1457019968.043656] [mm-base-manager.c:296] device_added(): (tty/ttyp5): could not get port's parent device <debug> [1457019968.043681] [mm-base-manager.c:296] device_added(): (tty/ttyp6): could not get port's parent device <debug> [1457019968.043707] [mm-base-manager.c:296] device_added(): (tty/ttyp7): could not get port's parent device <debug> [1457019968.043820] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043824] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043826] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043827] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwan0] filtered by drivers <debug> [1457019968.043829] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwan0] filtered by drivers <debug> [1457019968.043831] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwan0] filtered by subsystem <debug> [1457019968.043832] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwan0] filtered by subsystem <debug> [1457019968.043834] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwan0] filtered by subsystem <debug> [1457019968.043835] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwan0] filtered by udev tags <debug> [1457019968.043837] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwan0] filtered by subsystem <debug> [1457019968.043838] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwan0] filtered by subsystem <debug> [1457019968.043840] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043841] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwan0] filtered by subsystem <debug> [1457019968.043843] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043844] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043846] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwan0] filtered by subsystem <debug> [1457019968.043847] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043849] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043850] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwan0] filtered by drivers <debug> [1457019968.043852] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwan0] filtered by subsystem <debug> [1457019968.043853] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwan0] filtered by subsystem <debug> [1457019968.043855] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwan0] filtered by subsystem <debug> [1457019968.043856] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwan0] filtered by subsystem <debug> [1457019968.043857] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043859] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wwan0] filtered by vendor/product IDs <debug> [1457019968.043860] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwan0] Found '2' plugins to try... <debug> [1457019968.043862] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Huawei' <debug> [1457019968.043863] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Generic' <debug> [1457019968.043866] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested <debug> [1457019968.043928] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0] Checking device support... <debug> [1457019968.043947] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043949] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043951] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043952] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlan0] filtered by drivers <debug> [1457019968.043954] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers <debug> [1457019968.043956] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043957] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem <debug> [1457019968.043958] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem <debug> [1457019968.043959] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem <debug> [1457019968.043961] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags <debug> [1457019968.043962] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem <debug> [1457019968.043964] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem <debug> [1457019968.043965] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043967] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem <debug> [1457019968.043968] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043970] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043971] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem <debug> [1457019968.043972] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043974] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043975] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers <debug> [1457019968.043976] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by subsystem <debug> [1457019968.043978] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem <debug> [1457019968.043979] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem <debug> [1457019968.043980] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem <debug> [1457019968.043982] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043983] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs <debug> [1457019968.043984] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wlan0] Found '1' plugins to try... <debug> [1457019968.043986] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wlan0] Will try with plugin 'Generic' <debug> [1457019968.043988] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested <debug> [1457019968.044049] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1f.6] Checking device support... <debug> [1457019968.044066] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044068] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044070] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044072] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [eth0-link] filtered by drivers <debug> [1457019968.044073] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [eth0-link] filtered by drivers <debug> [1457019968.044075] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044076] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eth0-link] filtered by subsystem <debug> [1457019968.044077] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eth0-link] filtered by subsystem <debug> [1457019968.044079] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eth0-link] filtered by subsystem <debug> [1457019968.044080] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [eth0-link] filtered by udev tags <debug> [1457019968.044082] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eth0-link] filtered by subsystem <debug> [1457019968.044083] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eth0-link] filtered by subsystem <debug> [1457019968.044084] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044086] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eth0-link] filtered by subsystem <debug> [1457019968.044087] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044089] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044090] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eth0-link] filtered by subsystem <debug> [1457019968.044091] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044093] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044094] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [eth0-link] filtered by drivers <debug> [1457019968.044095] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [eth0-link] filtered by subsystem <debug> [1457019968.044097] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eth0-link] filtered by subsystem <debug> [1457019968.044098] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eth0-link] filtered by subsystem <debug> [1457019968.044099] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eth0-link] filtered by subsystem <debug> [1457019968.044101] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044102] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [eth0-link] filtered by vendor/product IDs <debug> [1457019968.044104] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [eth0-link] Found '1' plugins to try... <debug> [1457019968.044105] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [eth0-link] Will try with plugin 'Generic' <debug> [1457019968.044107] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [eth0-link] probing deferred until result suggested <debug> [1457019968.044145] [mm-base-manager.c:296] device_added(): (net/eth0): could not get port's parent device <debug> [1457019968.044173] [mm-base-manager.c:296] device_added(): (net/ipsec0): could not get port's parent device <debug> [1457019968.044200] [mm-base-manager.c:296] device_added(): (net/ipsec1): could not get port's parent device <debug> [1457019968.044226] [mm-base-manager.c:296] device_added(): (net/ipsec2): could not get port's parent device <debug> [1457019968.044252] [mm-base-manager.c:296] device_added(): (net/ipsec3): could not get port's parent device <debug> [1457019968.044301] [mm-base-manager.c:296] device_added(): (net/tap0): could not get port's parent device <debug> [1457019968.044328] [mm-base-manager.c:296] device_added(): (net/tap1): could not get port's parent device <debug> [1457019968.044357] [mm-base-manager.c:296] device_added(): (net/tap2): could not get port's parent device <debug> [1457019968.044384] [mm-base-manager.c:296] device_added(): (net/tap3): could not get port's parent device <debug> [1457019968.044411] [mm-base-manager.c:296] device_added(): (net/tap4): could not get port's parent device <debug> [1457019968.044436] [mm-base-manager.c:296] device_added(): (net/tap5): could not get port's parent device <debug> [1457019968.044526] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044529] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs <debug> [1457019968.044531] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by vendor/product IDs <debug> [1457019968.044532] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers <debug> [1457019968.044534] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044535] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044537] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044538] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044540] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags <debug> [1457019968.044541] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044542] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044544] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044545] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044547] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044548] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs <debug> [1457019968.044549] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044551] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs <debug> [1457019968.044552] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044554] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers <debug> [1457019968.044555] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044556] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044558] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044559] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem <debug> [1457019968.044560] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs <debug> [1457019968.044562] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs <debug> [1457019968.044563] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try... <debug> [1457019968.044565] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Huawei' <debug> [1457019968.044566] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic' <debug> [1457019968.044570] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [cdc-wdm0] probe required: 'mbim' <debug> [1457019968.044573] [mm-port-probe.c:1368] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim' <debug> [1457019968.044583] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested <debug> [1457019968.044587] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wlan0] deferring support check until result suggested <debug> [1457019968.044589] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [eth0-link] deferring support check until result suggested <debug> [1457019968.044679] [mm-port-serial.c:1120] mm_port_serial_open(): (ttyUSB0) opening serial port... <warn> [1457019968.044854] [mm-port-serial.c:534] real_config_fd(): (ttyUSB0): port attributes not fully set <debug> [1457019968.044868] [mm-port-serial.c:1258] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) <debug> [1457019968.044879] [mm-port-probe.c:525] wdm_probe_mbim(): (usbmisc/cdc-wdm0) probing MBIM... opening device...[/dev/cdc-wdm0] Read max control message size from descriptors file: 1024[/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:30:00:00:00 [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 1 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b) <<<<<< cid = 'configuration' (0x00000001) <<<<<< type = 'set' (0x00000001) <debug> [1457019968.145614] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^SQPORT?<CR>' <debug> [1457019969.151453] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>ERROR<CR><LF>' <debug> [1457019969.151728] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error <debug> [1457019969.151897] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>' <debug> [1457019969.353624] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>' <debug> [1457019969.353770] [mm-port-probe.c:154] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is AT-capable <debug> [1457019969.353883] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT+CGMI<CR>' <debug> [1457019970.001258] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3] Minimum probing time consumed <debug> [1457019970.001311] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0] Minimum probing time consumed <debug> [1457019970.001325] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [wlan0] deferred task cancelled, no suggested plugin <debug> [1457019970.001347] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1f.6] Minimum probing time consumed <debug> [1457019970.001356] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [eth0-link] deferred task cancelled, no suggested plugin <debug> [1457019970.001385] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [wlan0] not supported by any plugin <debug> [1457019970.001407] [mm-device.c:368] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on <debug> [1457019970.001433] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'wlan0' port probe finished, last one in device <debug> [1457019970.001448] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0] device support check finished in '1.957513' seconds <info> [1457019970.001552] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0': not supported by any plugin <debug> [1457019970.001611] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [eth0-link] not supported by any plugin <debug> [1457019970.001629] [mm-device.c:368] mm_device_ignore_port(): Fully ignoring port 'net/eth0-link' from now on <debug> [1457019970.001643] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'eth0-link' port probe finished, last one in device <debug> [1457019970.001654] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1f.6] device support check finished in '1.957599' seconds <info> [1457019970.001671] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1f.6': not supported by any plugin <debug> [1457019970.060275] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>Huawei Technologies Co., Ltd.<CR><LF><CR><LF>OK<CR><LF>' <debug> [1457019970.060436] [mm-port-probe.c:181] mm_port_probe_set_result_at_vendor(): (tty/ttyUSB0) vendor probing finished <debug> [1457019970.060559] [mm-port-serial.c:1315] _close_internal(): (ttyUSB0) device open count is 0 (close) <debug> [1457019970.060579] [mm-port-serial.c:1331] _close_internal(): (ttyUSB0) closing serial port... <debug> [1457019970.061100] [mm-port-serial.c:1380] _close_internal(): (ttyUSB0) serial port closed <debug> [1457019970.061144] [mm-port-serial.c:1442] port_serial_close_force(): (ttyUSB0) forced to close port <debug> [1457019970.061230] [mm-plugin.c:429] apply_post_probing_filters(): (Cinterion) [ttyUSB0] filtered by vendor strings <debug> [1457019970.061326] [mm-plugin.c:767] mm_plugin_supports_port(): (Iridium) [ttyUSB0] probe required: 'at, at-vendor, at-product' <debug> [1457019970.061346] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at-product' <debug> [1457019970.061573] [mm-port-serial.c:1120] mm_port_serial_open(): (ttyUSB0) opening serial port... <warn> [1457019970.061825] [mm-port-serial.c:534] real_config_fd(): (ttyUSB0): port attributes not fully set <debug> [1457019970.061895] [mm-port-serial.c:1258] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) <debug> [1457019970.162552] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT+CGMM<CR>' <debug> [1457019970.868420] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>ME906s-158<CR><LF><CR><LF>OK<CR><LF>' <debug> [1457019970.868565] [mm-port-probe.c:201] mm_port_probe_set_result_at_product(): (tty/ttyUSB0) product probing finished <debug> [1457019970.868672] [mm-port-serial.c:1315] _close_internal(): (ttyUSB0) device open count is 0 (close) <debug> [1457019970.868692] [mm-port-serial.c:1331] _close_internal(): (ttyUSB0) closing serial port... <debug> [1457019970.869222] [mm-port-serial.c:1380] _close_internal(): (ttyUSB0) serial port closed <debug> [1457019970.869261] [mm-port-serial.c:1442] port_serial_close_force(): (ttyUSB0) forced to close port <debug> [1457019970.869343] [mm-plugin.c:477] apply_post_probing_filters(): (Iridium) [ttyUSB0] filtered by vendor/product strings <debug> [1457019970.869486] [mm-plugin.c:767] mm_plugin_supports_port(): (Nokia) [ttyUSB0] probe required: 'at, at-vendor, at-icera' <debug> [1457019970.869512] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at-icera' <debug> [1457019970.869765] [mm-port-serial.c:1120] mm_port_serial_open(): (ttyUSB0) opening serial port... <warn> [1457019970.870040] [mm-port-serial.c:534] real_config_fd(): (ttyUSB0): port attributes not fully set <debug> [1457019970.870098] [mm-port-serial.c:1258] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) <debug> [1457019970.970465] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT%IPSYS?<CR>' <debug> [1457019971.875308] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>ERROR<CR><LF>' <debug> [1457019971.875391] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error <debug> [1457019971.875444] [mm-port-probe.c:921] serial_probe_at_parse_response(): (tty/ttyUSB0) re-scheduling next command in probing group in 2 seconds... <debug> [1457019974.001454] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT%IPSYS?<CR>' <debug> [1457019974.906508] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>ERROR<CR><LF>' <debug> [1457019974.906592] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error <debug> [1457019974.906643] [mm-port-probe.c:921] serial_probe_at_parse_response(): (tty/ttyUSB0) re-scheduling next command in probing group in 2 seconds... <debug> [1457019977.003200] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT%IPSYS?<CR>' <debug> [1457019977.908373] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>ERROR<CR><LF>' <debug> [1457019977.908457] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error <debug> [1457019977.908510] [mm-port-probe.c:226] mm_port_probe_set_result_at_icera(): (tty/ttyUSB0) Modem is probably not Icera-based <debug> [1457019977.908578] [mm-port-serial.c:1315] _close_internal(): (ttyUSB0) device open count is 0 (close) <debug> [1457019977.908593] [mm-port-serial.c:1331] _close_internal(): (ttyUSB0) closing serial port... <debug> [1457019977.909086] [mm-port-serial.c:1380] _close_internal(): (ttyUSB0) serial port closed <debug> [1457019977.909128] [mm-port-serial.c:1442] port_serial_close_force(): (ttyUSB0) forced to close port <debug> [1457019977.909222] [mm-plugin.c:429] apply_post_probing_filters(): (Nokia) [ttyUSB0] filtered by vendor strings <debug> [1457019977.909326] [mm-plugin.c:767] mm_plugin_supports_port(): (Via CBP7) [ttyUSB0] probe required: 'at, at-vendor, at-product' <debug> [1457019977.909381] [mm-plugin.c:477] apply_post_probing_filters(): (Via CBP7) [ttyUSB0] filtered by vendor/product strings <debug> [1457019977.909426] [mm-plugin.c:767] mm_plugin_supports_port(): (Generic) [ttyUSB0] probe required: 'at, qcdm' <debug> [1457019977.909468] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Generic) [ttyUSB0] found best plugin for port <debug> [1457019977.909509] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Generic) [wwan0] deferred task completed, got suggested plugin <debug> [1457019977.909533] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, still 2 running probes in this device (cdc-wdm0, wwan0) <debug> [1457019977.909572] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [wwan0] probing deferred until result suggested <debug> [1457019977.909597] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Generic) [wwan0] task completed, got suggested plugin <debug> [1457019977.909610] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 1 running probes in this device (cdc-wdm0) proxy configuration failed: closed<debug> [1457019998.067528] [mm-port-probe.c:503] mbim_port_open_ready(): (usbmisc/cdc-wdm0) error checking MBIM support: 'Transaction timed out' <debug> [1457019998.067566] [mm-port-probe.c:320] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is not MBIM-capable <debug> [1457019998.067724] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [cdc-wdm0] found best plugin for port <debug> [1457019998.067760] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Huawei) [cdc-wdm0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) <debug> [1457019998.067814] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, last one in device <debug> [1457019998.067838] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3] device support check finished in '30.025227' seconds <info> [1457019998.067887] [mm-device.c:520] mm_device_create_modem(): Creating modem with plugin 'Huawei' and '3' ports <debug> [1457019998.070654] [huawei/mm-plugin-huawei.c:558] grab_port(): (usbmisc/cdc-wdm0) Port will have AT flags 'none' <warn> [1457019998.070708] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm0): 'Cannot add port 'usbmisc/cdc-wdm0', unsupported' <debug> [1457019998.070727] [huawei/mm-plugin-huawei.c:558] grab_port(): (net/wwan0) Port will have AT flags 'none' <debug> [1457019998.070759] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3 <debug> [1457019998.070786] [huawei/mm-plugin-huawei.c:558] grab_port(): (tty/ttyUSB0) Port will have AT flags 'ppp' <debug> [1457019998.070934] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB0) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3 <debug> [1457019998.070995] [mm-port-serial.c:1442] port_serial_close_force(): (ttyUSB0) forced to close port <debug> [1457019998.071109] [mm-base-modem.c:1485] finalize(): Modem (Huawei) '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' completely disposed <warn> [1457019998.071129] [mm-base-manager.c:158] find_device_support_ready(): Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3': Failed to find primary AT port
signature.asc
Description: OpenPGP digital signature
_______________________________________________ ModemManager-devel mailing list ModemManager-devel@lists.freedesktop.org https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
