ModemManager-1.6.2: does not work with Huawei E3372h ??? -153

Sat Jan 14 00:16:07 UTC 2017

> Could you send the full ModemManager debug logs when the modem is
> plugged in? I guess we'll just see AT commands timing out during the
> probing of cdc-wdm0, but who knows.

Hi,
  so here I attach some logs I managed to acquire. If they are not what you wanted, please send me the command I should run on my side.

  I think NM could save some effort by first checking for /proc/sys/net/ipv6/conf/ttyUSB0, and only after that proceed with more detailed checks. Second, does ttyUSB0 support IPv6 at all? It does same for tun0 and tun1 interfaces. I just do not have IPv6 enabled in kernel, at all.

NetworkManager[6488]: <debug> [1484351360.9385] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9385] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9385] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9386] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9386] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9386] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/hop_limit: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/hop_limit': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9386] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr': No such file or directory
NetworkManager[6488]: <debug> [1484351360.9386] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6': (2) No such file or directory
NetworkManager[6488]: <debug> [1484351360.9387] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr': (2) No such file or directory
NetworkManager[6488]: <debug> [1484351360.9387] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo': (2) No such file or directory
NetworkManager[6488]: <debug> [1484351360.9387] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref': (2) No such file or directory
NetworkManager[6488]: <debug> [1484351360.9387] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr': (2) No such file or directory

  Anyway, there are a few lines about the cdc-wdm0 but does not seem to be much verbose. A few AT commands failed but on the ttyUSB1.
Martin
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ModemManager_debug.log
Type: text/x-log
Size: 129858 bytes
Desc: not available
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20170114/af012757/attachment-0002.bin>
-------------- next part --------------
Jan 14 00:33:14 vostro ModemManager[8115]: <info>  ModemManager (version 1.6.4) starting in system bus...
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8481] auth: call[1]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8484] auth: call[2]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8501] auth: call[3]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8524] auth: call[4]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8549] auth: call[5]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8575] auth: call[6]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8601] auth: call[7]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8624] auth: call[8]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8649] auth: call[9]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8673] auth: call[10]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8702] auth: call[11]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8728] auth: call[12]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8754] auth: call[13]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8784] auth: call[14]: CheckAuthorization succeeded: (is_authorized=0, is_challenge=1)
Jan 14 00:33:14 vostro NetworkManager[8099]: <debug> [1484350394.8809] auth: call[15]: CheckAuthorization succeeded: (is_authorized=1, is_challenge=0)
Jan 14 00:33:14 vostro dbus[5416]: [system] Successfully activated service 'org.freedesktop.ModemManager1'
Jan 14 00:33:14 vostro NetworkManager[8099]: <info>  [1484350394.8844] ModemManager disappeared from bus
Jan 14 00:33:14 vostro NetworkManager[8099]: <info>  [1484350394.9763] ModemManager available in the bus
Jan 14 00:33:17 vostro ModemManager[8115]: <info>  Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:1c.1/0000:05:00.0': not supported by any plugin
Jan 14 00:33:17 vostro ModemManager[8115]: <info>  Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:1c.3/0000:09:00.0': not supported by any plugin
Jan 14 00:33:19 vostro NetworkManager[8099]: <debug> [1484350399.7113] device[0xc90a10] (enp5s0): remove_pending_action (0): 'carrier wait'
Jan 14 00:33:19 vostro NetworkManager[8099]: <info>  [1484350399.7114] manager: startup complete
Jan 14 00:33:30 vostro su[8121]: Successful su for root by mmokrejs
Jan 14 00:33:30 vostro su[8121]: + /dev/pts/2 mmokrejs:root
Jan 14 00:33:30 vostro su[8121]: pam_unix(su:session): session opened for user root by mmokrejs(uid=9999)
Jan 14 00:33:35 vostro kernel: usb 2-1.2: new high-speed USB device number 6 using ehci-pci
Jan 14 00:33:36 vostro kernel: usb 2-1.2: New USB device found, idVendor=12d1, idProduct=14fe
Jan 14 00:33:36 vostro kernel: usb 2-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan 14 00:33:36 vostro kernel: usb 2-1.2: Product: HUAWEI_MOBILE
Jan 14 00:33:36 vostro kernel: usb 2-1.2: Manufacturer: HUAWEI_MOBILE
Jan 14 00:33:36 vostro kernel: usb 2-1.2: SerialNumber: 0123456789ABCDEF
Jan 14 00:33:36 vostro kernel: usb-storage 2-1.2:1.0: USB Mass Storage device detected
Jan 14 00:33:36 vostro kernel: scsi host6: usb-storage 2-1.2:1.0
Jan 14 00:33:35 vostro usb_modeswitch[8159]: switch device 12d1:14fe on 002/006
Jan 14 00:33:36 vostro kernel: usb 2-1.2: USB disconnect, device number 6
Jan 14 00:33:37 vostro kernel: usb 2-1.2: new high-speed USB device number 7 using ehci-pci
Jan 14 00:33:37 vostro kernel: usb 2-1.2: New USB device found, idVendor=12d1, idProduct=1506
Jan 14 00:33:37 vostro kernel: usb 2-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jan 14 00:33:37 vostro kernel: usb 2-1.2: Product: HUAWEI_MOBILE
Jan 14 00:33:37 vostro kernel: usb 2-1.2: Manufacturer: HUAWEI_MOBILE
Jan 14 00:33:37 vostro kernel: option 2-1.2:1.0: GSM modem (1-port) converter detected
Jan 14 00:33:37 vostro kernel: usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB0
Jan 14 00:33:37 vostro kernel: option 2-1.2:1.1: GSM modem (1-port) converter detected
Jan 14 00:33:37 vostro kernel: usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB1
Jan 14 00:33:38 vostro kernel: huawei_cdc_ncm 2-1.2:1.2: MAC-Address: 00:1e:10:1f:00:00
Jan 14 00:33:38 vostro kernel: huawei_cdc_ncm 2-1.2:1.2: setting rx_max = 16384
Jan 14 00:33:37 vostro NetworkManager[8099]: <trace> [1484350417.1031] platform-linux: event-notification: NEWLINK, seq 0: 7: wwan0 <DOWN;broadcast,multicast> mtu 1500 arp 1 wwan? not-init addr 00:1E:10:1F:00:00 rx:0,0 tx:0,0
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1032] platform: signal: link   added: 7: wwan0 <DOWN;broadcast,multicast> mtu 1500 arp 1 wwan? not-init addr 00:1E:10:1F:00:00 driver huawei_cdc_ncm rx:0,0 tx:0,0
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1033] manager: wwan0: factory failed to create device: Device factory NMWwanFactory ignores device wwan0
Jan 14 00:33:38 vostro kernel: huawei_cdc_ncm 2-1.2:1.2: NDP will be placed at end of frame for this device.
Jan 14 00:33:38 vostro kernel: huawei_cdc_ncm 2-1.2:1.2: cdc-wdm0: USB WDM device
Jan 14 00:33:38 vostro kernel: huawei_cdc_ncm 2-1.2:1.2 wwan0: register 'huawei_cdc_ncm' at usb-0000:00:1d.0-1.2, Huawei CDC NCM device, 00:1e:10:1f:00:00
Jan 14 00:33:38 vostro kernel: usb-storage 2-1.2:1.3: USB Mass Storage device detected
Jan 14 00:33:38 vostro kernel: scsi host6: usb-storage 2-1.2:1.3
Jan 14 00:33:38 vostro kernel: usb-storage 2-1.2:1.4: USB Mass Storage device detected
Jan 14 00:33:38 vostro kernel: scsi host7: usb-storage 2-1.2:1.4
Jan 14 00:33:38 vostro kernel: huawei_cdc_ncm 2-1.2:1.2 wwp0s29u1u2i2: renamed from wwan0
Jan 14 00:33:37 vostro NetworkManager[8099]: <trace> [1484350417.1566] platform-linux: event-notification: NEWLINK, seq 0: 7: wwp0s29u1u2i2 <DOWN;broadcast,multicast> mtu 1500 arp 1 wwan? not-init addr 00:1E:10:1F:00:00 rx:0,0 tx:0,0
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1567] platform: signal: link changed: 7: wwp0s29u1u2i2 <DOWN;broadcast,multicast> mtu 1500 arp 1 wwan? not-init addr 00:1E:10:1F:00:00 driver huawei_cdc_ncm rx:0,0 tx:0,0
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1587] platform-linux: UDEV event: action 'add' subsys 'net' device 'wwp0s29u1u2i2' (7); seqnum=4506
Jan 14 00:33:37 vostro NetworkManager[8099]: <trace> [1484350417.1588] platform-linux: udev-add[wwp0s29u1u2i2,7]: device added
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1589] platform: signal: link changed: 7: wwp0s29u1u2i2 <DOWN;broadcast,multicast> mtu 1500 arp 1 wwan? init addr 00:1E:10:1F:00:00 driver huawei_cdc_ncm rx:0,0 tx:0,0
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1616] platform-linux: UDEV event: action 'move' subsys 'net' device 'wwp0s29u1u2i2' (7); seqnum=4515
Jan 14 00:33:37 vostro NetworkManager[8099]: <trace> [1484350417.1616] platform-linux: udev-add[wwp0s29u1u2i2,7]: device added
Jan 14 00:33:37 vostro NetworkManager[8099]: <debug> [1484350417.1618] platform: signal: link changed: 7: wwp0s29u1u2i2 <DOWN;broadcast,multicast> mtu 1500 arp 1 wwan? init addr 00:1E:10:1F:00:00 driver huawei_cdc_ncm rx:0,0 tx:0,0
Jan 14 00:33:37 vostro root[8206]: usb_modeswitch: switched to 12d1:1506 on 002/007
Jan 14 00:33:39 vostro kernel: scsi 7:0:0:0: Direct-Access     HUAWEI   TF CARD Storage  2.31 PQ: 0 ANSI: 2
Jan 14 00:33:39 vostro kernel: sd 7:0:0:0: Attached scsi generic sg2 type 0
Jan 14 00:33:39 vostro kernel: sd 7:0:0:0: [sdb] Attached SCSI removable disk
Jan 14 00:33:39 vostro kernel: scsi 6:0:0:0: CD-ROM            HUAWEI   Mass Storage     2.31 PQ: 0 ANSI: 2
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] scsi-1 drive
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: Attached scsi CD-ROM sr1
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: Attached scsi generic sg3 type 5
Jan 14 00:33:39 vostro kernel: scsi_io_completion: 4 callbacks suppressed
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8d fc 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: 4 callbacks suppressed
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 145392
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8d fc 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 145392
Jan 14 00:33:39 vostro kernel: Buffer I/O error on dev sr1, logical block 18174, async page read
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8c 80 00 00 3c 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 143872
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8c bc 00 00 04 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 144112
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8c 80 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 143872
Jan 14 00:33:39 vostro kernel: Buffer I/O error on dev sr1, logical block 17984, async page read
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8d fa 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 145384
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8d fa 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 145384
Jan 14 00:33:39 vostro kernel: Buffer I/O error on dev sr1, logical block 18173, async page read
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8d f6 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 145368
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8d f6 00 00 02 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 145368
Jan 14 00:33:39 vostro kernel: Buffer I/O error on dev sr1, logical block 18171, async page read
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Sense Key : Medium Error [current] 
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 Add. Sense: Unrecovered read error
Jan 14 00:33:39 vostro kernel: sr 6:0:0:0: [sr1] tag#0 CDB: Read(10) 28 00 00 00 8c 80 00 00 3c 00
Jan 14 00:33:39 vostro kernel: blk_update_request: critical medium error, dev sr1, sector 143872
Jan 14 00:33:39 vostro kernel: Buffer I/O error on dev sr1, logical block 17984, async page read
Jan 14 00:33:39 vostro kernel: Buffer I/O error on dev sr1, logical block 18170, async page read
Jan 14 00:33:38 vostro ModemManager[8115]: <warn>  (ttyUSB0): port attributes not fully set
Jan 14 00:33:38 vostro ModemManager[8115]: <warn>  (ttyUSB1): port attributes not fully set
Jan 14 00:33:41 vostro ModemManager[8115]: <warn>  (ttyUSB1): port attributes not fully set
Jan 14 00:33:47 vostro ModemManager[8115]: <info>  Creating modem with plugin 'Huawei' and '4' ports
Jan 14 00:33:47 vostro ModemManager[8115]: <warn>  Could not grab port (usbmisc/cdc-wdm0): 'Cannot add port 'usbmisc/cdc-wdm0', unsupported'
Jan 14 00:33:47 vostro ModemManager[8115]: <warn>  (ttyUSB0): port attributes not fully set
Jan 14 00:33:47 vostro ModemManager[8115]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2' successfully created
Jan 14 00:33:51 vostro ModemManager[8115]: <warn>  couldn't load list of Own Numbers: 'Not found'
Jan 14 00:33:51 vostro ModemManager[8115]: <info>  Modem: state changed (unknown -> disabled)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9789] device[0xce0ea0] (ttyUSB0): constructed (NMDeviceModem)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9789] device[0xce0ea0] (ttyUSB0): start setup of NMDeviceModem, kernel ifindex 0
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9789] device[0xce0ea0] (ttyUSB0): unmanaged: flags set to [platform-init,!loopback=0x10/0x18/unmanaged/unrealized, set-managed [loopback=0x8])
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9790] device[0xce0ea0] (ttyUSB0): unmanaged: flags set to [!loopback,!platform-init=0x0/0x18/managed/unrealized, set-managed [platform-init=0x10])
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9790] manager: WWAN hw-enabled 1 sw-enabled 1
Jan 14 00:33:51 vostro NetworkManager[8099]: <info>  [1484350431.9790] (ttyUSB0): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9791] device[0xce0ea0] (ttyUSB0): unmanaged: flags set to [!loopback,!platform-init,!user-settings=0x0/0x58/managed/unrealized, set-managed [user-settings=0x40])
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9791] device[0xce0ea0] (ttyUSB0): unmanaged: flags set to [!sleeping,!loopback,!platform-init,!user-settings=0x0/0x59/managed/unrealized, set-managed [sleeping=0x1])
Jan 14 00:33:51 vostro NetworkManager[8099]: <trace> [1484350431.9794] exported-object[0xce0ea0]: export: "/org/freedesktop/NetworkManager/Devices/3"
Jan 14 00:33:51 vostro NetworkManager[8099]: <info>  [1484350431.9796] manager: (ttyUSB0): new Broadband device (/org/freedesktop/NetworkManager/Devices/3)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9798] manager: (ttyUSB0): can't assume; no connection
Jan 14 00:33:51 vostro NetworkManager[8099]: <info>  [1484350431.9799] device (ttyUSB0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9800] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9800] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9801] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9801] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9801] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9801] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/hop_limit: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/hop_limit': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9801] platform-linux: error reading /proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr: Failed to open file '/proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr': No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9802] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9802] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9802] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9802] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9802] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9802] device[0xce0ea0] (ttyUSB0): bringing up device
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9803] device[0xce0ea0] (ttyUSB0): preparing device
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9803] device[0xce0ea0] (ttyUSB0): clearing queued IP4 config change
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9803] device[0xce0ea0] (ttyUSB0): clearing queued IP6 config change
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9803] device[0xce0ea0] (ttyUSB0): remove_pending_action (0): 'dhcp6' not pending (expected)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9804] device[0xce0ea0] (ttyUSB0): remove_pending_action (0): 'autoconf6' not pending (expected)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9804] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9804] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9804] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9810] device[0xce0ea0] (ttyUSB0): ip4-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9811] device[0xce0ea0] (ttyUSB0): ip6-config: update (commit=1, routes-full-sync=1, new-config=(nil))
Jan 14 00:33:51 vostro NetworkManager[8099]: <info>  [1484350431.9812] device (ttyUSB0): modem state 'enabling'
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9814] device[0xce0ea0] (ttyUSB0): add_pending_action (1): 'queued state change to disconnected'
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9814] device[0xce0ea0] (ttyUSB0): queued state change to disconnected due to none (id 157)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9814] device[0xce0ea0] (ttyUSB0): is available, will transition to disconnected
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9815] device[0xce0ea0] (ttyUSB0): running queued state change to disconnected (id 157)
Jan 14 00:33:51 vostro NetworkManager[8099]: <info>  [1484350431.9815] device (ttyUSB0): state change: unavailable -> disconnected (reason 'none') [20 30 0]
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9816] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/disable_ipv6': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9816] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_defrtr': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9821] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_pinfo': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9821] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/accept_ra_rtr_pref': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9821] platform-linux: sysctl: failed to open '/proc/sys/net/ipv6/conf/ttyUSB0/use_tempaddr': (2) No such file or directory
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9822] device[0xce0ea0] (ttyUSB0): add_pending_action (2): 'autoactivate'
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9822] device[0xce0ea0] (ttyUSB0): remove_pending_action (1): 'queued state change to disconnected'
Jan 14 00:33:51 vostro ModemManager[8115]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Jan 14 00:33:51 vostro NetworkManager[8099]: <debug> [1484350431.9825] device[0xce0ea0] (ttyUSB0): remove_pending_action (0): 'autoactivate'
Jan 14 00:33:51 vostro ModemManager[8115]: <warn>  (ttyUSB0): port attributes not fully set
Jan 14 00:33:52 vostro ModemManager[8115]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
Jan 14 00:33:52 vostro ModemManager[8115]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
Jan 14 00:33:52 vostro ModemManager[8115]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
Jan 14 00:33:52 vostro NetworkManager[8099]: <info>  [1484350432.8555] (ttyUSB0): modem state changed, 'enabling' --> 'registered' (reason: user-requested)
-------------- next part --------------
A non-text attachment was scrubbed...
Name: debug2.log
Type: text/x-log
Size: 93948 bytes
Desc: not available
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20170114/af012757/attachment-0003.bin>