<div dir="ltr"><br><div class="gmail_extra"><br><div class="gmail_quote">On Fri, Aug 18, 2017 at 3:43 AM, Aleksander Morgado <span dir="ltr"><<a href="mailto:aleksander@aleksander.es" target="_blank">aleksander@aleksander.es</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">Hey,<br>
<div><div class="gmail-h5"><br>
><br>
> I'm looking into an issue with MM (built with udev) occasionally failing to<br>
> find the net port when doing the initial probing of a MBIM modem.  Have you<br>
> seen something similar before?<br>
><br>
> Thanks,<br>
> Ben<br>
><br>
> ###<br>
><br>
><br>
> 2017-08-17T20:04:35.062308-07:<wbr>00 INFO kernel: [ 4903.471298] usb 1-3: new<br>
> high-speed USB device number 14 using xhci-hcd<br>
> 2017-08-17T20:04:35.082205-07:<wbr>00 INFO kernel: [ 4903.495755] usb 1-3: New<br>
> USB device found, idVendor=12d1, idProduct=15bb<br>
> 2017-08-17T20:04:35.082235-07:<wbr>00 INFO kernel: [ 4903.495768] usb 1-3: New<br>
> USB device strings: Mfr=6, Product=5, SerialNumber=0<br>
> 2017-08-17T20:04:35.082242-07:<wbr>00 INFO kernel: [ 4903.495776] usb 1-3:<br>
> Product: HUAWEI Mobile Broadband Module<br>
> 2017-08-17T20:04:35.082247-07:<wbr>00 INFO kernel: [ 4903.495783] usb 1-3:<br>
> Manufacturer: Huawei Technologies Co., Ltd.<br>
> 2017-08-17T20:04:35.092179-07:<wbr>00 INFO kernel: [ 4903.503156] usb 1-3:<br>
> MAC-Address: xx:xx:xx:xx:xx:xx<br>
> 2017-08-17T20:04:35.092208-07:<wbr>00 INFO kernel: [ 4903.503542] cdc_ncm 1-3:2.0<br>
> usb0: register 'cdc_ncm' at usb-xhci-hcd.0.auto-3, CDC NCM,<br>
> xx:xx:xx:xx:xx:xx<br>
> 2017-08-17T20:04:35.102279-07:<wbr>00 INFO kernel: [ 4903.516219] cdc_ncm 1-3:2.0<br>
> usb0: unregister 'cdc_ncm' usb-xhci-hcd.0.auto-3, CDC NCM<br>
><br>
> # Switching from USB configuration 2 (NCM) -> USB configuration 3 (MBIM) ...<br>
> net/usb0 is removed and then net/wwan0 is added<br>
><br>
> 2017-08-17T20:04:35.162402-07:<wbr>00 INFO kernel: [ 4903.573001] cdc_mbim<br>
> 1-3:3.0: cdc-wdm0: USB WDM device<br>
> 2017-08-17T20:04:35.162432-07:<wbr>00 INFO kernel: [ 4903.574327] cdc_mbim<br>
> 1-3:3.0 wwan0: register 'cdc_mbim' at usb-xhci-hcd.0.auto-3, CDC MBIM,<br>
> xx:xx:xx:xx:xx:xx<br>
> 2017-08-17T20:04:35.191646-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (usbmisc/cdc-wdm0): adding device at sysfs path:<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3/<wbr>1-3:3.0/usbmisc/cdc-wdm0<br>
><br>
> # cdc-wdm is considered the first port of<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3<br>
><br>
> 2017-08-17T20:04:35.192518-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (usbmisc/cdc-wdm0): first port in device<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3<br>
> 2017-08-17T20:04:35.192628-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2: new support task for device:<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3<br>
> 2017-08-17T20:04:35.192895-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2: port grabbed: cdc-wdm0<br>
> 2017-08-17T20:04:35.192916-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: new support task for port<br>
> 2017-08-17T20:04:35.192930-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager) task 2,cdc-wdm0: deferred until min wait time elapsed<br>
><br>
> # This device removal looks suspicious ... I'm guessing it's related to<br>
> net/usb0<br>
><br>
> 2017-08-17T20:04:35.193998-07:<wbr>00 DEBUG ModemManager[26503]: <debug> Removing<br>
> device '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3'<br>
<br>
</div></div>Yes, this is definitely the issue here, looks like we're getting a<br>
"removed" udev event for the usb device AFTER we got the "add" event<br>
for the "cdc-wdm" port, or a least that is how I interpret it. So,<br>
when we got the cdc-wdm port we create a MMDevice and start probing<br>
it, and we track it in the internal tables; then we get the usb<br>
removal event and we end up removing that new MMDevice from the<br>
tracking table, and then we get the add event for wwan0 and we end up<br>
creating yet another MMDevice for that one. None of the MMDevices end<br>
up creating a modem because both lack something (the first one lacks<br>
the net port, the second one lacks the control port).<br>
<br>
Could you update mm-base-manager.c:device_<wbr>removed() to log the subsys<br>
and name of all events received? that will give us more info.<br>
<br>
The logic that does that MMDevice removal is documented like this:<br>
<br>
    /* This case is designed to handle the case where, at least with<br>
kernel 2.6.31, unplugging<br>
     * an in-use ttyACMx device results in udev generating remove<br>
events for the usb, but the<br>
     * ttyACMx device (subsystem tty) is not removed, since it was<br>
in-use.  So if we have not<br>
     * found a modem for the port (above), we're going to look here to<br>
see if we have a modem<br>
     * associated with the newly removed device.  If so, we'll remove<br>
the modem, since the<br>
     * device has been removed.  That way, if the device is reinserted<br>
later, we'll go through<br>
     * the process of exporting it.<br>
     */<br>
<br>
That means that if we get a remove event for the parent USB device and<br>
there is still a MMDevice around, we remove the MMDevice. This is a<br>
special case.<br>
The normal flow is to get remove events per-port and when the last<br>
port is removed, we remove the empty MMDevice.<br>
<br>
>From my point of view, the order of events received from udev isn't<br>
the expected one, and we should confirm that. Actually, it may very<br>
well just be that the removal event we get isn't a removal event for<br>
the whole USB device, and it's just the removal event for the "usb0"<br>
interface, which for some reason isn't flagged as "net" subsystem and<br>
it's flagged as "usb" subsystem... Again, printing subsys/name in that<br>
method will give us more details.<br>
<div class="gmail-HOEnZb"><div class="gmail-h5"><br>
<br></div></div></blockquote><div><br></div><div>It looks like when the USB configuration is switch from 2 to 3. The udev events of the removal of interfaces associated with config 2 and the addition of interfaces associated with config 3 could be interleaved. Here's an example that the removal event of 1-3:2.2 and 1-3:2.4 are issued after the addition of 1-3:3.0/usbmisc/cdc-wdm0 but before the addition of 1-3:3.0/net/wwan0.   All of them are associated with the same device "/devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3". <br></div><div><br></div><div><br></div><div><div>UDEV  [60231.057732] add      /devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:3.0/usbmisc/cdc-wdm0 (usbmisc)</div><div>ACTION=add</div><div>DEVNAME=/dev/cdc-wdm0</div><div>DEVPATH=/devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:3.0/usbmisc/cdc-wdm0</div><div>ID_MM_CANDIDATE=1</div><div>ID_MM_DEVICE_IS_MODEM=1</div><div>MAJOR=180</div><div>MINOR=176</div><div>SEQNUM=2029</div><div>SUBSYSTEM=usbmisc</div><div>USEC_INITIALIZED=60231057407</div><div><br></div><div>UDEV  [60231.060390] remove   /devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:2.2 (usb)</div><div>ACTION=remove</div><div>DEVPATH=/devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:2.2</div><div>DEVTYPE=usb_interface</div><div>ID_USB_CLASS_FROM_DATABASE=Miscellaneous Device</div><div>ID_USB_PROTOCOL_FROM_DATABASE=Interface Association</div><div>INTERFACE=255/5/16</div><div>MODALIAS=usb:v12D1p15BBd0001dcEFdsc02dp01icFFisc05ip10in02</div><div>PRODUCT=12d1/15bb/1</div><div>SEQNUM=2023</div><div>SUBSYSTEM=usb</div><div>TYPE=239/2/1</div><div>USEC_INITIALIZED=60231049203</div><div><br></div><div>UDEV  [60231.061816] remove   /devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:2.4 (usb)</div><div>ACTION=remove</div><div>DEVPATH=/devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:2.4</div><div>DEVTYPE=usb_interface</div><div>ID_USB_CLASS_FROM_DATABASE=Miscellaneous Device</div><div>ID_USB_PROTOCOL_FROM_DATABASE=Interface Association</div><div>INTERFACE=255/5/18</div><div>MODALIAS=usb:v12D1p15BBd0001dcEFdsc02dp01icFFisc05ip12in04</div><div>PRODUCT=12d1/15bb/1</div><div>SEQNUM=2025</div><div>SUBSYSTEM=usb</div><div>TYPE=239/2/1</div><div>USEC_INITIALIZED=60231053657</div><div><br></div><div>UDEV  [60231.065329] add      /devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:3.0/net/wwan0 (net)</div><div>ACTION=add</div><div>DEVPATH=/devices/soc0/70090000.usb/xhci-hcd.0.auto/usb1/1-3/1-3:3.0/net/wwan0</div><div>DEVTYPE=wwan</div><div>ID_BUS=usb</div><div>ID_MM_CANDIDATE=1</div><div>ID_MODEL=HUAWEI_Mobile_Broadband_Module</div><div>ID_MODEL_ENC=HUAWEI\x20Mobile\x20Broadband\x20Module</div><div>ID_MODEL_ID=15bb</div><div>ID_NET_DRIVER=cdc_mbim</div><div>ID_NET_NAME_MAC=wwx5ae7c1ec981c</div><div>ID_PATH=platform-xhci-hcd.0.auto-usb-0:3:3.0</div><div>ID_PATH_TAG=platform-xhci-hcd_0_auto-usb-0_3_3_0</div><div>ID_REVISION=0001</div><div>ID_SERIAL=Huawei_Technologies_Co.__Ltd._HUAWEI_Mobile_Broadband_Module</div><div>ID_TYPE=generic</div><div>ID_USB_CLASS_FROM_DATABASE=Miscellaneous Device</div><div>ID_USB_DRIVER=cdc_mbim</div><div>ID_USB_INTERFACES=:ff0512:ff0513:ff0510:ff0516:ff0514:020d00:0a0000:020e00:0a0002:</div><div>ID_USB_INTERFACE_NUM=00</div><div>ID_USB_PROTOCOL_FROM_DATABASE=Interface Association</div><div>ID_VENDOR=Huawei_Technologies_Co.__Ltd.</div><div>ID_VENDOR_ENC=Huawei\x20Technologies\x20Co.\x2c\x20Ltd.</div><div>ID_VENDOR_ID=12d1</div><div>IFINDEX=42</div><div>INTERFACE=wwan0</div><div>SEQNUM=2030</div><div>SUBSYSTEM=net</div><div>USEC_INITIALIZED=60231063661</div></div><div><br></div><div><br></div><div><br></div><div> </div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div class="gmail-HOEnZb"><div class="gmail-h5">
<br>
> 2017-08-17T20:04:<a href="tel:35.196198-07" value="+13519619807">35.196198-07</a>:00 DEBUG ModemManager[26503]: <debug><br>
> (net/wwan0): adding device at sysfs path:<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3/<wbr>1-3:3.0/net/wwan0<br>
><br>
> # wwan0 should be considered as an additional port of the same device, but<br>
> considered the first port..  perhaps related to the device removal above<br>
><br>
> 2017-08-17T20:04:35.197025-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (net/wwan0): first port in device<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3<br>
><br>
> # The following error also looks kind of weird<br>
><br>
> 2017-08-17T20:04:35.197660-07:<wbr>00 INFO ModemManager[26503]: <info>  Couldn't<br>
> check support for device<br>
> '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3': Device support<br>
> check task already available for device<br>
> '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3'<br>
><br>
> 2017-08-17T20:04:35.199626-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (net/usb0): adding device at sysfs path:<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3/<wbr>1-3:2.0/net/usb0<br>
> 2017-08-17T20:04:35.200104-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (net/usb0): first port in device<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3<br>
> 2017-08-17T20:04:35.200446-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (net/usb0) could not get vendor/product id<br>
> 2017-08-17T20:04:35.200540-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (net/usb0) could not get vendor/product id<br>
> 2017-08-17T20:04:35.200912-07:<wbr>00 INFO ModemManager[26503]: <info>  Couldn't<br>
> check support for device<br>
> '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3': Device support<br>
> check task already available for device<br>
> '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3'<br>
> 2017-08-17T20:04:36.694918-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2: min wait time elapsed<br>
> 2017-08-17T20:04:36.694963-07:<wbr>00 DEBUG ModemManager[26503]: <debug> (Novatel<br>
> LTE) [cdc-wdm0] filtered by subsystem<br>
> 2017-08-17T20:04:36.694993-07:<wbr>00 DEBUG ModemManager[26503]: <debug> (Telit)<br>
> [cdc-wdm0] filtered by vendor/product IDs<br>
> 2017-08-17T20:04:36.695019-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (Samsung) [cdc-wdm0] filtered by subsystem<br>
> 2017-08-17T20:04:36.695045-07:<wbr>00 DEBUG ModemManager[26503]: <debug> (ZTE)<br>
> [cdc-wdm0] filtered by vendor/product IDs<br>
> 2017-08-17T20:04:36.695069-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (Longcheer) [cdc-wdm0] filtered by subsystem<br>
> 2017-08-17T20:04:36.695094-07:<wbr>00 DEBUG ModemManager[26503]: <debug> (Altair<br>
> LTE) [cdc-wdm0] filtered by subsystem<br>
> 2017-08-17T20:04:36.695128-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: found '2' plugins to try<br>
> 2017-08-17T20:04:36.695151-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: will try with plugin 'Huawei'<br>
> 2017-08-17T20:04:36.695174-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: will try with plugin 'Generic'<br>
> 2017-08-17T20:04:36.695216-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager) task 2,cdc-wdm0: started<br>
> 2017-08-17T20:04:36.695867-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: checking with plugin 'Huawei'<br>
> 2017-08-17T20:04:36.696037-07:<wbr>00 DEBUG ModemManager[26503]: <debug> (Huawei)<br>
> [cdc-wdm0] probe required: 'mbim'<br>
> 2017-08-17T20:04:36.696086-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (usbmisc/cdc-wdm0) launching port probing: 'mbim'<br>
> 2017-08-17T20:04:36.696142-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (usbmisc/cdc-wdm0) probing MBIM...<br>
> 2017-08-17T20:04:36.697733-07:<wbr>00 DEBUG ModemManager[26503]: opening<br>
> device...<br>
> 2017-08-17T20:04:36.698239-07:<wbr>00 DEBUG ModemManager[26503]: cannot connect<br>
> to proxy: Could not connect: Connection refused<br>
> 2017-08-17T20:04:36.698275-07:<wbr>00 DEBUG ModemManager[26503]: spawning new<br>
> mbim-proxy (try 1)...<br>
> 2017-08-17T20:04:36.807686-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Read max control message size from descriptors file: 1024<br>
> 2017-08-17T20:04:36.808578-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Sent message...#012<<<<<< RAW:#012<<<<<<   length = 88#012<<<<<<   data   =<br>
> 03:00:00:00:58:00:00:00:01:00:<wbr>00:00:01:00:00:00:00:00:00:00:<wbr>83:8C:F7:FB:8D:0D:4D:7F:87:1E:<wbr>D7:1D:BE:FB:B3:9B:01:00:00:00:<wbr>01:00:00:00:28:00:00:00:0C:00:<wbr>00:00:1A:00:00:00:1E:00:00:00:<wbr>2F:00:64:00:65:00:76:00:2F:00:<wbr>63:00:64:00:63:00:2D:00:77:00:<wbr>64:00:6D:00:30:00:00:00<br>
> 2017-08-17T20:04:36.809129-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Sent message (translated)...#012<<<<<< Header:#012<<<<<<   length      =<br>
> 88#012<<<<<<   type        = command (0x00000003)#012<<<<<<   transaction =<br>
> 1#012<<<<<< Fragment header:#012<<<<<<   total   = 1#012<<<<<<   current =<br>
> 0#012<<<<<< Contents:#012<<<<<<   service = 'proxy-control'<br>
> (838cf7fb-8d0d-4d7f-871e-<wbr>d71dbefbb39b)#012<<<<<<   cid     = 'configuration'<br>
> (0x00000001)#012<<<<<<   type    = 'set' (0x00000001)<br>
> 2017-08-17T20:04:37.027805-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Received message...#012>>>>>> RAW:#012>>>>>>   length = 48#012>>>>>>   data<br>
> =<br>
> 03:00:00:80:30:00:00:00:01:00:<wbr>00:00:01:00:00:00:00:00:00:00:<wbr>83:8C:F7:FB:8D:0D:4D:7F:87:1E:<wbr>D7:1D:BE:FB:B3:9B:01:00:00:00:<wbr>00:00:00:00:00:00:00:00<br>
> 2017-08-17T20:04:37.027952-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Received message (translated)...#012>>>>>> Header:#012>>>>>>   length      =<br>
> 48#012>>>>>>   type        = command-done (0x80000003)#012>>>>>><br>
> transaction = 1#012>>>>>> Fragment header:#012>>>>>>   total   = 1#012>>>>>><br>
> current = 0#012>>>>>> Contents:#012>>>>>>   status error = 'None'<br>
> (0x00000000)#012>>>>>>   service      = 'proxy-control'<br>
> (838cf7fb-8d0d-4d7f-871e-<wbr>d71dbefbb39b)#012>>>>>>   cid          =<br>
> 'configuration' (0x00000001)<br>
> 2017-08-17T20:04:37.028108-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Sent message...#012<<<<<< RAW:#012<<<<<<   length = 16#012<<<<<<   data   =<br>
> 01:00:00:00:10:00:00:00:02:00:<wbr>00:00:00:04:00:00<br>
> 2017-08-17T20:04:37.028178-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Sent message (translated)...#012<<<<<< Header:#012<<<<<<   length      =<br>
> 16#012<<<<<<   type        = open (0x00000001)#012<<<<<<   transaction =<br>
> 2#012<<<<<< Contents:#012<<<<<<   max_control_transfer = 1024<br>
> 2017-08-17T20:04:37.029093-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Received message...#012>>>>>> RAW:#012>>>>>>   length = 16#012>>>>>>   data<br>
> = 01:00:00:80:10:00:00:00:02:00:<wbr>00:00:00:00:00:00<br>
> 2017-08-17T20:04:37.029324-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (usbmisc/cdc-wdm0) port is MBIM-capable<br>
> 2017-08-17T20:04:37.029426-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Sent message...#012<<<<<< RAW:#012<<<<<<   length = 12#012<<<<<<   data   =<br>
> 02:00:00:00:0C:00:00:00:03:00:<wbr>00:00<br>
> 2017-08-17T20:04:37.029492-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Sent message (translated)...#012<<<<<< Header:#012<<<<<<   length      =<br>
> 12#012<<<<<<   type        = close (0x00000002)#012<<<<<<   transaction = 3<br>
> 2017-08-17T20:04:37.030365-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> Received message...#012>>>>>> RAW:#012>>>>>>   length = 16#012>>>>>>   data<br>
> = 02:00:00:80:10:00:00:00:03:00:<wbr>00:00:00:00:00:00<br>
> 2017-08-17T20:04:37.030483-07:<wbr>00 DEBUG ModemManager[26503]: [/dev/cdc-wdm0]<br>
> channel destroyed<br>
> 2017-08-17T20:04:37.030891-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: found best plugin for port (Huawei)<br>
> 2017-08-17T20:04:37.030961-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: finished in '1.838009' seconds<br>
> 2017-08-17T20:04:37.031016-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2,cdc-wdm0: found best plugin: Huawei<br>
> 2017-08-17T20:04:37.031066-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2: no more ports to probe<br>
> 2017-08-17T20:04:37.031115-07:<wbr>00 DEBUG ModemManager[26503]: <debug> [plugin<br>
> manager] task 2: finished in '1.838466' seconds<br>
> 2017-08-17T20:04:37.031244-07:<wbr>00 INFO ModemManager[26503]: <info>  [device<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3] creating modem with<br>
> plugin 'Huawei' and '1' ports<br>
> 2017-08-17T20:04:37.031338-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> MBIM-powered Huawei modem found...<br>
> 2017-08-17T20:04:37.037442-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (usbmisc/cdc-wdm0) Port will have AT flags 'none'<br>
> 2017-08-17T20:04:37.037618-07:<wbr>00 DEBUG ModemManager[26503]: <debug><br>
> (cdc-wdm0) type 'mbim' claimed by<br>
> /sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3<br>
> 2017-08-17T20:04:37.037892-07:<wbr>00 DEBUG ModemManager[26503]: <debug> Modem<br>
> (Huawei) '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3'<br>
> completely disposed<br>
><br>
> # Seems like wwan0 wasn't probed along with cdc-wdm0, so no net port was<br>
> found<br>
><br>
> 2017-08-17T20:04:37.037971-07:<wbr>00 WARNING ModemManager[26503]: <warn><br>
> Couldn't create modem for device<br>
> '/sys/devices/soc0/70090000.<wbr>usb/xhci-hcd.0.auto/usb1/1-3': Failed to find a<br>
> net port in the MBIM modem<br>
><br>
<br>
<br>
<br>
</div></div><span class="gmail-HOEnZb"><font color="#888888">--<br>
Aleksander<br>
<a href="https://aleksander.es" rel="noreferrer" target="_blank">https://aleksander.es</a><br>
</font></span></blockquote></div><br></div></div>