Author Message

<  Setup Discussion  ~  Modems not switching in Beaglebone Black Arch Linux

PostPosted: Sun Feb 12, 2017 12:50 pm Reply with quote
Posts: 5Joined: Sun Feb 12, 2017 12:22 pm
Hi there, I'm getting mad with my Beaglebone Black running ArchLinux and 3G USB modems. After hours researching in the internet I'm unable to make any modem switch, so beg for some expert advice...

I started using Arch back in 2015 with kernel 3.8: 3G USB modems switched flawlessly and I could use them. Even after upgrading the whole system except the kernel to the current versions of libusb and usb_modeswitch (libusb-1.0.21-1-armv7h.pkg.tar.xz and usb_modeswitch-2.4.0-2.1-armv7h.pkg.tar.xz).

This is what I get with kernel 3.8 for a Huawei e1550:

[root@bbb ~]# dmesg
...
[ 5.574138] usb usb1: usb wakeup-resume
[ 5.574192] usb usb1: usb auto-resume
[ 5.574214] hub 1-0:1.0: hub_resume
[ 5.574263] hub 1-0:1.0: port 1: status 0101 change 0001
[ 5.677160] hub 1-0:1.0: state 7 ports 1 chg 0002 evt 0000
[ 5.677218] hub 1-0:1.0: port 1, status 0101, change 0000, 12 Mb/s
[ 5.782639] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[ 5.909092] usb 1-1: default language 0x0409
[ 5.913007] usb 1-1: udev 2, busnum 1, minor = 1
[ 5.913030] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 5.913043] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 5.913054] usb 1-1: Product: HUAWEI Mobile
[ 5.913064] usb 1-1: Manufacturer: HUAWEI Technology
[ 5.913619] usb 1-1: usb_probe_device
[ 5.913639] usb 1-1: configuration #1 chosen from 1 choice
[ 5.917436] usb 1-1: adding 1-1:1.0 (config #1, interface 0)
[ 5.917750] usb-storage 1-1:1.0: usb_probe_interface
[ 5.917797] usb-storage 1-1:1.0: usb_probe_interface - got id
[ 5.927215] scsi0 : usb-storage 1-1:1.0
[ 5.927900] usb 1-1: adding 1-1:1.1 (config #1, interface 1)
[ 5.928109] usb-storage 1-1:1.1: usb_probe_interface
[ 5.928138] usb-storage 1-1:1.1: usb_probe_interface - got id
[ 5.942537] scsi1 : usb-storage 1-1:1.1
[ 5.943357] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 5.943410] hub 1-0:1.0: port 1 enable change, status 00000503
[ 6.934533] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 6.946840] sr0: scsi-1 drive
[ 6.946868] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 6.947631] sr 0:0:0:0: Attached scsi CD-ROM sr0
[ 6.948234] sr 0:0:0:0: Attached scsi generic sg0 type 5
[ 6.952795] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 6.955649] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 6.968922] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 7.626186] omap_rng 48310000.rng: base address of priv is -97452032
[ 7.673424] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[ 8.150135] net eth0: initializing cpsw version 1.12 (0)
[ 8.161966] net eth0: phy found : id is : 0x7c0f1
[ 8.162009] libphy: PHY 4a101000.mdio:01 not found
[ 8.167115] net eth0: phy 4a101000.mdio:01 not found on slave 1
[ 8.214623] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 11.240631] libphy: 4a101000.mdio:00 - Link is Up - 100/Full
[ 11.240708] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 15.345138] tun: Universal TUN/TAP device driver, 1.6
[ 15.345164] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[ 16.265991] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 16.266060] hub 1-0:1.0: port 1, status 0100, change 0001, 12 Mb/s
[ 16.266079] usb 1-1: USB disconnect, device number 2
[ 16.266091] usb 1-1: unregistering device
[ 16.266102] usb 1-1: unregistering interface 1-1:1.0
[ 16.266447] usb 1-1: unregistering interface 1-1:1.1
[ 16.271078] usb 1-1: usb_disable_device nuking all URBs
[ 16.382243] hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x100
[ 16.382335] hub 1-0:1.0: hub_suspend
[ 16.382366] usb usb1: bus auto-suspend, wakeup 1
[ 22.584219] usb usb1: usb wakeup-resume
[ 22.584272] usb usb1: usb auto-resume
[ 22.584293] hub 1-0:1.0: hub_resume
[ 22.584342] hub 1-0:1.0: port 1: status 0101 change 0001
[ 22.687091] hub 1-0:1.0: state 7 ports 1 chg 0002 evt 0000
[ 22.687156] hub 1-0:1.0: port 1, status 0101, change 0000, 12 Mb/s
[ 22.792506] usb 1-1: new high-speed USB device number 3 using musb-hdrc
[ 22.918908] usb 1-1: default language 0x0409
[ 22.922906] usb 1-1: udev 3, busnum 1, minor = 2
[ 22.922933] usb 1-1: New USB device found, idVendor=12d1, idProduct=140c
[ 22.922946] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 22.922957] usb 1-1: Product: HUAWEI Mobile
[ 22.922967] usb 1-1: Manufacturer: HUAWEI Technology
[ 22.923578] usb 1-1: usb_probe_device
[ 22.923599] usb 1-1: configuration #1 chosen from 1 choice
[ 22.928665] usb 1-1: adding 1-1:1.0 (config #1, interface 0)
[ 22.928937] usb-storage 1-1:1.0: usb_probe_interface
[ 22.928962] usb-storage 1-1:1.0: usb_probe_interface - got id
[ 22.936059] usb 1-1: adding 1-1:1.1 (config #1, interface 1)
[ 22.936293] usb-storage 1-1:1.1: usb_probe_interface
[ 22.936317] usb-storage 1-1:1.1: usb_probe_interface - got id
[ 22.941388] usb 1-1: adding 1-1:1.2 (config #1, interface 2)
[ 22.941614] usb-storage 1-1:1.2: usb_probe_interface
[ 22.941638] usb-storage 1-1:1.2: usb_probe_interface - got id
[ 22.943986] usb 1-1: adding 1-1:1.3 (config #1, interface 3)
[ 22.944198] usb-storage 1-1:1.3: usb_probe_interface
[ 22.944221] usb-storage 1-1:1.3: usb_probe_interface - got id
[ 22.949107] usb 1-1: adding 1-1:1.4 (config #1, interface 4)
[ 22.949329] usb-storage 1-1:1.4: usb_probe_interface
[ 22.949354] usb-storage 1-1:1.4: usb_probe_interface - got id
[ 22.958525] scsi6 : usb-storage 1-1:1.4
[ 22.959187] usb 1-1: adding 1-1:1.5 (config #1, interface 5)
[ 22.959374] usb-storage 1-1:1.5: usb_probe_interface
[ 22.959396] usb-storage 1-1:1.5: usb_probe_interface - got id
[ 22.969621] scsi7 : usb-storage 1-1:1.5
[ 22.970419] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 22.970473] hub 1-0:1.0: port 1 enable change, status 00000503
[ 23.263611] usbcore: registered new interface driver usbserial
[ 23.263705] usbcore: registered new interface driver usbserial_generic
[ 23.263769] usbserial: USB Serial support registered for generic
[ 23.450685] usbcore: registered new interface driver cdc_wdm
[ 23.491853] qmi_wwan 1-1:1.1: usb_probe_interface
[ 23.491883] qmi_wwan 1-1:1.1: usb_probe_interface - got id
[ 23.503643] usbcore: registered new interface driver option
[ 23.510819] qmi_wwan 1-1:1.1: looking for a minor, starting at 176
[ 23.517700] usbserial: USB Serial support registered for GSM modem (1-port)
[ 23.529448] qmi_wwan 1-1:1.1: cdc-wdm0: USB WDM device
[ 23.549467] qmi_wwan 1-1:1.1 wwan0: register 'qmi_wwan' at usb-musb-hdrc.1.auto-1, WWAN/QMI device, c2:63:af:bb:ea:da
[ 23.553998] option 1-1:1.0: usb_probe_interface
[ 23.554032] option 1-1:1.0: usb_probe_interface - got id
[ 23.556867] option 1-1:1.0: GSM modem (1-port) converter detected
[ 23.560193] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
[ 23.562135] option 1-1:1.2: usb_probe_interface
[ 23.562159] option 1-1:1.2: usb_probe_interface - got id
[ 23.562195] option 1-1:1.2: GSM modem (1-port) converter detected
[ 23.564114] usbcore: registered new interface driver qmi_wwan
[ 23.571986] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
[ 23.572156] option 1-1:1.3: usb_probe_interface
[ 23.572176] option 1-1:1.3: usb_probe_interface - got id
[ 23.572207] option 1-1:1.3: GSM modem (1-port) converter detected
[ 23.575852] usb 1-1: GSM modem (1-port) converter now attached to ttyUSB2
[ 23.965945] scsi 6:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 23.974407] scsi 7:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 23.978205] sr0: scsi-1 drive
[ 23.978993] sr 6:0:0:0: Attached scsi CD-ROM sr0
[ 23.979623] sr 6:0:0:0: Attached scsi generic sg0 type 5
[ 23.983120] sd 7:0:0:0: Attached scsi generic sg1 type 0
[ 23.997383] sd 7:0:0:0: [sda] Attached SCSI removable disk
[ 54.159489] PPP generic driver version 2.4.2
[ 56.417167] PPP BSD Compression module registered
[ 56.436698] PPP Deflate Compression module registered
[ 113.746859] cgroup: systemd (1) created nested cgroup for controller "blkio" which has incomplete hierarchy support. Nested cgroups may change behavior in the future.

[root@bbb ~]# lsmod
Module Size Used by
binfmt_misc 6616 1
ppp_deflate 3871 0
bsd_comp 4892 0
ppp_async 7241 1
ppp_generic 25407 7 bsd_comp,ppp_async,ppp_deflate
slhc 5241 1 ppp_generic
option 20743 1
qmi_wwan 5681 0
cdc_wdm 9151 1 qmi_wwan
usb_wwan 7846 1 option
usbserial 24019 4 option,usb_wwan
tun 16879 2
omap_rng 4532 0
ip_tables 10945 0
x_tables 16740 1 ip_tables
autofs4 21912 2

Unfortunately, using later kernels (>4) there's no way to make the same modem switch. This is what I get:

[root@bbb ~]# cat /var/log/usb_modeswitch_1-1\:1.0

USB_ModeSwitch log from Sun Feb 12 11:27:25 CET 2017

Use global config file: /etc/usb_modeswitch.conf

Started via systemd
Raw args from udev: 1-1_1-1:1.0

Use top device dir /sys/bus/usb/devices/1-1
Check class of first interface ...
Interface 0 class is 08.

----------------
USB values from sysfs:
manufacturer HUAWEI Technology
product HUAWEI Mobile
serial
----------------
bNumConfigurations is 1 - don't check for active configuration
ConfigList: /usr/share/usb_modeswitch/12d1:1446 /usr/share/usb_modeswitch/12d1:#linux
SCSI attributes not needed, move on
Check config: /usr/share/usb_modeswitch/12d1:1446
! matched. Read config data
Unbinding driver
Command to be run:
usb_modeswitch -W -D -u -1 -b 1 -g 4 -v 12d1 -p 1446 -f $flags(config)

Verbose debug output of usb_modeswitch and libusb follows
(Note that some USB errors are to be expected in the process)
--------------------------------

Read long config from command line

* usb_modeswitch: handle USB devices with multiple modes
* Version 2.4.0 (C) Josua Dietze 2016
* Based on libusb1/libusbx

! PLEASE REPORT NEW CONFIGURATIONS !

DefaultVendor= 0x12d1
DefaultProduct= 0x1446
TargetVendor= 0x12d1
TargetProductList="1001,1404,1406,140b,140c,1412,1417,141b,1429,1432,1433,1436,14ac,1506,150c,1511"
HuaweiNewMode=1
System integration mode enabled

Use given bus/device number: 001/004 ...
Look for default devices ...
bus/device number matched
found USB ID 12d1:1446
vendor ID matched
product ID matched
Found devices in default mode (1)
Get the current device configuration ...
Use interface number 0
Use endpoints 0x01 (out) and 0x81 (in)

USB description data (for identification)
-------------------------
Manufacturer: HUAWEI Technology
Product: HUAWEI Mobile
Serial No.: not provided
-------------------------
Using standard Huawei switching message
Looking for active driver ...
No active driver found. Detached before or never attached
Set up interface 0
Use endpoint 0x01 for message sending ...
Trying to send message 1 to endpoint 0x01 ...
OK, message successfully sent
Read the response to message 1 (CSW) ...
Response reading failed (error -9)
Device is gone, skip any further commands
ok:busdev
--------------------------------
(end of usb_modeswitch output)

Check success of mode switch for max. 20 seconds ...
Wait for device file system (1 sec.) ...
Wait for device file system (2 sec.) ...
Wait for device file system (3 sec.) ...
Wait for device file system (4 sec.) ...
Wait for device file system (5 sec.) ...
Wait for device file system (6 sec.) ...
Wait for device file system (7 sec.) ...
Wait for device file system (8 sec.) ...
Wait for device file system (9 sec.) ...
Wait for device file system (10 sec.) ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...
Read attributes ...

Target config not matching - current values are
1-1:1.0/bInterfaceClass: 08
bConfigurationValue: 1
bNumConfigurations: 1
busnum: 1
devnum: 5
idProduct: 1446
idVendor: 12d1
manufacturer: HUAWEI Technology
product: HUAWEI Mobile
serial:

Mode switching may have failed. Exit

[root@bbb ~]# lsmod
Module Size Used by
sr_mod 13474 0
cdrom 49673 1 sr_mod
ti_am335x_adc 5340 0
kfifo_buf 2962 1 ti_am335x_adc
industrialio 48914 2 ti_am335x_adc,kfifo_buf
snd_soc_hdmi_codec 6223 1
ti_am335x_tscadc 5304 1 ti_am335x_adc
snd_soc_simple_card 6468 0
snd_soc_simple_card_utils 4357 1 snd_soc_simple_card
omap_sham 21739 0
omap_aes 12929 0
crypto_engine 5142 1 omap_aes
snd_soc_davinci_mcasp 17083 2
snd_soc_omap 3221 1 snd_soc_davinci_mcasp
snd_soc_edma 1218 1 snd_soc_davinci_mcasp
snd_soc_core 148611 6 snd_soc_davinci_mcasp,snd_soc_edma,snd_soc_hdmi_codec,snd_soc_simple_card_utils,snd_soc_omap,snd_soc_simple_card
omap_rng 4572 0
rng_core 6854 1 omap_rng
tilcdc 28218 0
snd_pcm_dmaengine 4432 2 snd_soc_omap,snd_soc_core
snd_pcm 76446 5 snd_soc_davinci_mcasp,snd_pcm_dmaengine,snd_soc_hdmi_codec,snd_soc_omap,snd_soc_core
snd_timer 17279 1 snd_pcm
snd 54031 3 snd_timer,snd_soc_core,snd_pcm
soundcore 6363 1 snd
evdev 9971 1
tps65217_charger 4723 0
tda998x 12452 0
omap_wdt 4191 0
sch_fq_codel 7437 2
ip_tables 9985 0
x_tables 15954 1 ip_tables


[root@bbb ~]# dmesg

[ 15.953258] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[ 16.134480] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 16.141222] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 16.148413] usb 1-1: Product: HUAWEI Mobile
[ 16.152611] usb 1-1: Manufacturer: HUAWEI Technology
[ 16.168685] usb-storage 1-1:1.0: USB Mass Storage device detected
[ 16.176296] scsi host0: usb-storage 1-1:1.0
[ 16.182602] usb-storage 1-1:1.1: USB Mass Storage device detected
[ 16.192732] scsi host1: usb-storage 1-1:1.1
[ 17.174003] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[ 17.182111] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 17.256809] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 17.267972] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 17.288407] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 17.453520] sr 0:0:0:0: [sr0] scsi-1 drive
[ 17.457684] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 17.488969] sr 0:0:0:0: Attached scsi CD-ROM sr0
[ 17.621678] usb 1-1: USB disconnect, device number 2
[ 28.353263] usb 1-1: new high-speed USB device number 3 using musb-hdrc
[ 28.534466] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 28.541204] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 28.548396] usb 1-1: Product: HUAWEI Mobile
[ 28.552594] usb 1-1: Manufacturer: HUAWEI Technology
[ 28.568685] usb-storage 1-1:1.0: USB Mass Storage device detected
[ 28.575855] scsi host0: usb-storage 1-1:1.0
[ 28.582026] usb-storage 1-1:1.1: USB Mass Storage device detected
[ 28.592160] scsi host1: usb-storage 1-1:1.1
[ 29.657544] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 29.668275] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 29.687193] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 29.710642] sr 0:0:0:0: [sr0] scsi-1 drive
[ 29.719524] sr 0:0:0:0: Attached scsi CD-ROM sr0
[ 527.295721] usb 1-1: USB disconnect, device number 3
[ 538.010750] usb 1-1: new high-speed USB device number 4 using musb-hdrc
[ 538.192071] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 538.198811] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 538.206002] usb 1-1: Product: HUAWEI Mobile
[ 538.210199] usb 1-1: Manufacturer: HUAWEI Technology
[ 538.225032] usb-storage 1-1:1.0: USB Mass Storage device detected
[ 538.232843] scsi host0: usb-storage 1-1:1.0
[ 538.239035] usb-storage 1-1:1.1: USB Mass Storage device detected
[ 538.249354] scsi host1: usb-storage 1-1:1.1
[ 539.274291] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 539.285213] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 539.309264] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 539.320328] sr 0:0:0:0: [sr0] scsi-1 drive
[ 539.339926] sr 0:0:0:0: Attached scsi CD-ROM sr0
[ 539.672727] usb 1-1: USB disconnect, device number 4
[ 550.421816] usb 1-1: new high-speed USB device number 5 using musb-hdrc
[ 550.602972] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 550.609718] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 550.616926] usb 1-1: Product: HUAWEI Mobile
[ 550.621124] usb 1-1: Manufacturer: HUAWEI Technology
[ 550.637193] usb-storage 1-1:1.0: USB Mass Storage device detected
[ 550.644384] scsi host0: usb-storage 1-1:1.0
[ 550.654978] usb-storage 1-1:1.1: USB Mass Storage device detected
[ 550.661453] scsi host1: usb-storage 1-1:1.1
[ 551.676469] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 551.687215] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 551.705909] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 551.729290] sr 0:0:0:0: [sr0] scsi-1 drive
[ 551.739289] sr 0:0:0:0: Attached scsi CD-ROM sr0

----------------------------------------------------------------------------------

I have tried:

-several specific configurations for device 12d1:1446,
-downgrading kernel,
-downgrading libusb and usb_modeswitch,
-disabling sr_mod and cdrom,
-disabling usb-storage,
...

but no luck.

Any help is very much appreciated.


Offline Profile
PostPosted: Sun Feb 12, 2017 1:34 pm Reply with quote
Site AdminPosts: 6361Joined: Sat Nov 03, 2007 12:30 am
Disabling of the storage driver is not a good idea. Some modems require its 'first touch' to mode-switch correctly.

What I would try first is to increase the "use_delay" of usb-storage.

You can do it by echoing a value of 4 or above to the respective sys tree attribute of the driver, or you can use the convenience function in usb_modeswitch (see /etc/usb_modeswitch.conf).


Offline Profile
PostPosted: Sun Feb 12, 2017 2:00 pm Reply with quote
Posts: 5Joined: Sun Feb 12, 2017 12:22 pm
Hi Josh, I also tried that with different values at:

SetStorageDelay=8

... no effect. Still get the same behavior.


Offline Profile
PostPosted: Sun Feb 12, 2017 2:18 pm Reply with quote
Posts: 1156Location: Koh Samui, THJoined: Wed Jul 11, 2012 3:14 pm
darzoz wrote:


[ 15.953258] usb 1-1: new high-speed USB device number 2 using musb-hdrc

[ 17.621678] usb 1-1: USB disconnect, device number 2
[ 28.353263] usb 1-1: new high-speed USB device number 3 using musb-hdrc

[ 527.295721] usb 1-1: USB disconnect, device number 3
[ 538.010750] usb 1-1: new high-speed USB device number 4 using musb-hdrc

[ 539.672727] usb 1-1: USB disconnect, device number 4
[ 550.421816] usb 1-1: new high-speed USB device number 5 using musb-hdrc



Is it you plugging/unplugging/plugging/unplugging the dongle?
If not then you either have a usb port or driver problem or the usb port can't provide enough power for the dongle so it will reset itself. In the latter case try with a separately powered usb hub in between.

Edit: You have 2 usb ports on the Beaglebone, right? Try the other port.


Offline Profile
PostPosted: Sun Feb 12, 2017 2:26 pm Reply with quote
Posts: 5Joined: Sun Feb 12, 2017 12:22 pm
Hi LOM,
it was probably me trying to usb_modeswitch manually. I have two boards, one with kernel 3.8 in which the modem switches properly and another one with kernel 4.9 where it fails...

This is a clean dmesg from start in the board with 4.9 kernel:

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.9.8-1-ARCH (builduser@leming) (gcc version 6.3.1 20170109 (GCC) ) #1 Sun Feb 5 18:53:35 MST 2017
[ 0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=50c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ 0.000000] OF: fdt:Machine model: TI AM335x BeagleBone Black
[ 0.000000] cma: Reserved 48 MiB at 0x9d000000
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] On node 0 totalpages: 131072
[ 0.000000] free_area_init_node: node 0, pgdat c0dc92c4, node_mem_map dcb61000
[ 0.000000] Normal zone: 1152 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 131072 pages, LIFO batch:31
[ 0.000000] CPU: All CPU(s) started in SVC mode.
[ 0.000000] AM335X ES2.1 (sgx neon)
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 129920
[ 0.000000] Kernel command line: console=ttyO0,115200n8 coherent_pool=1M root=PARTUUID=2e555a54-01 rw rootwait fixrtc
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Memory: 455700K/524288K available (7168K kernel code, 981K rwdata, 3664K rodata, 1024K init, 786K bss, 19436K reserved, 49152K cma-reserved, 0K highmem)
[ 0.000000] Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xe0800000 - 0xff800000 ( 496 MB)
lowmem : 0xc0000000 - 0xe0000000 ( 512 MB)
pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
modules : 0xbf800000 - 0xbfe00000 ( 6 MB)
.text : 0xc0008000 - 0xc0800000 (8160 kB)
.init : 0xc0c00000 - 0xc0d00000 (1024 kB)
.data : 0xc0d00000 - 0xc0df5650 ( 982 kB)
.bss : 0xc0df7000 - 0xc0ebbb20 ( 787 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:16 nr_irqs:16 16
[ 0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[ 0.000000] OMAP clockevent source: timer2 at 24000000 Hz
[ 0.000010] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[ 0.000028] clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[ 0.000037] OMAP clocksource: timer1 at 24000000 Hz
[ 0.000856] clocksource_probe: no matching clocksources found
[ 0.001017] Console: colour dummy device 80x30
[ 0.001039] WARNING: Your 'console=ttyO0' has been replaced by 'ttyS0'
[ 0.001044] This ensures that you still see kernel messages. Please
[ 0.001049] update your kernel commandline.
[ 0.001067] Calibrating delay loop... 996.14 BogoMIPS (lpj=4980736)
[ 0.089100] pid_max: default: 32768 minimum: 301
[ 0.089331] Security Framework initialized
[ 0.089517] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.089526] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.090365] CPU: Testing write buffer coherency: ok
[ 0.090419] ftrace: allocating 33602 entries in 66 pages
[ 0.172555] Setting up static identity map for 0x80100000 - 0x8010004c
[ 0.176578] devtmpfs: initialized
[ 0.189906] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[ 0.190259] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.193587] xor: measuring software checksum speed
[ 0.289072] arm4regs : 1222.400 MB/sec
[ 0.389068] 8regs : 1095.200 MB/sec
[ 0.489067] 32regs : 1092.800 MB/sec
[ 0.589067] neon : 1766.000 MB/sec
[ 0.589074] xor: using function: neon (1766.000 MB/sec)
[ 0.589089] pinctrl core: initialized pinctrl subsystem
[ 0.590685] NET: Registered protocol family 16
[ 0.593457] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.605071] omap_hwmod: debugss: _wait_target_disable failed
[ 0.657771] cpuidle: using governor menu
[ 0.663473] OMAP GPIO hardware version 0.1
[ 0.675892] No ATAGs?
[ 0.675914] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.849308] raid6: int32x1 gen() 271 MB/s
[ 1.019202] raid6: int32x1 xor() 232 MB/s
[ 1.189169] raid6: int32x2 gen() 390 MB/s
[ 1.359132] raid6: int32x2 xor() 295 MB/s
[ 1.529114] raid6: int32x4 gen() 421 MB/s
[ 1.699173] raid6: int32x4 xor() 283 MB/s
[ 1.869073] raid6: int32x8 gen() 368 MB/s
[ 2.039085] raid6: int32x8 xor() 247 MB/s
[ 2.209075] raid6: neonx1 gen() 1464 MB/s
[ 2.379101] raid6: neonx1 xor() 849 MB/s
[ 2.549066] raid6: neonx2 gen() 1926 MB/s
[ 2.619065] random: fast init done
[ 2.719084] raid6: neonx2 xor() 1166 MB/s
[ 2.889078] raid6: neonx4 gen() 1223 MB/s
[ 3.059089] raid6: neonx4 xor() 871 MB/s
[ 3.229085] raid6: neonx8 gen() 1121 MB/s
[ 3.399111] raid6: neonx8 xor() 682 MB/s
[ 3.399117] raid6: using algorithm neonx2 gen() 1926 MB/s
[ 3.399123] raid6: .... xor() 1166 MB/s, rmw enabled
[ 3.399128] raid6: using intx1 recovery algorithm
[ 3.406372] edma 49000000.edma: TI EDMA DMA engine driver
[ 3.409944] SCSI subsystem initialized
[ 3.410215] libata version 3.00 loaded.
[ 3.410477] usbcore: registered new interface driver usbfs
[ 3.410538] usbcore: registered new interface driver hub
[ 3.410608] usbcore: registered new device driver usb
[ 3.411010] omap_i2c 44e0b000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c0_pins, deferring probe
[ 3.411052] omap_i2c 4819c000.i2c: could not find pctldev for node /ocp/l4_wkup@44c00000/scm@210000/pinmux@800/pinmux_i2c2_pins, deferring probe
[ 3.411134] media: Linux media interface: v0.10
[ 3.411179] Linux video capture interface: v2.00
[ 3.411248] pps_core: LinuxPPS API ver. 1 registered
[ 3.411254] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[ 3.411273] PTP clock support registered
[ 3.411701] omap-mailbox 480c8000.mailbox: omap mailbox rev 0x400
[ 3.412638] NetLabel: Initializing
[ 3.412649] NetLabel: domain hash size = 128
[ 3.412653] NetLabel: protocols = UNLABELED CIPSOv4
[ 3.412709] NetLabel: unlabeled traffic allowed by default
[ 3.413257] clocksource: Switched to clocksource timer1
[ 3.489850] VFS: Disk quotas dquot_6.6.0
[ 3.489930] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 3.499801] NET: Registered protocol family 2
[ 3.500458] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 3.500500] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[ 3.500537] TCP: Hash tables configured (established 4096 bind 4096)
[ 3.500595] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 3.500622] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 3.500747] NET: Registered protocol family 1
[ 3.501205] RPC: Registered named UNIX socket transport module.
[ 3.501214] RPC: Registered udp transport module.
[ 3.501219] RPC: Registered tcp transport module.
[ 3.501224] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 3.501976] hw perfevents: enabled with armv7_cortex_a8 PMU driver, 5 counters available
[ 3.503736] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 3.504603] workingset: timestamp_bits=14 max_order=17 bucket_order=3
[ 3.512555] zbud: loaded
[ 3.515453] NFS: Registering the id_resolver key type
[ 3.515491] Key type id_resolver registered
[ 3.515497] Key type id_legacy registered
[ 3.515513] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 3.515760] fuse init (API version 7.26)
[ 3.516261] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[ 3.522718] Key type asymmetric registered
[ 3.522731] Asymmetric key parser 'x509' registered
[ 3.522971] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[ 3.523106] io scheduler noop registered
[ 3.523114] io scheduler deadline registered
[ 3.523381] io scheduler cfq registered (default)
[ 3.524767] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[ 3.526282] wkup_m3_ipc 44e11324.wkup_m3_ipc: could not get rproc handle
[ 3.527572] Serial: 8250/16550 driver, 6 ports, IRQ sharing disabled
[ 3.529869] 44e09000.serial: ttyS0 at MMIO 0x44e09000 (irq = 158, base_baud = 3000000) is a 8250
[ 4.283735] console [ttyS0] enabled
[ 4.288284] [drm] Initialized
[ 4.292223] usbcore: registered new interface driver dln2
[ 4.298646] libphy: Fixed MDIO Bus: probed
[ 4.373318] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 4.379448] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[ 4.386014] davinci_mdio: dt: updated phy_id[0] from phy_mask[fffffffe]
[ 4.393637] libphy: 4a101000.mdio: probed
[ 4.397676] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver SMSC LAN8710/LAN8720
[ 4.407502] cpsw 4a100000.ethernet: Detected MACID = 78:a5:04:ca:e3:5c
[ 4.415804] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 4.422397] ehci-platform: EHCI generic platform driver
[ 4.427817] ehci-omap: OMAP-EHCI Host Controller driver
[ 4.433417] usbcore: registered new interface driver uas
[ 4.438822] usbcore: registered new interface driver usb-storage
[ 4.444899] usbcore: registered new interface driver ums-cypress
[ 4.452849] 47401300.usb-phy supply vcc not found, using dummy regulator
[ 4.462879] musb-hdrc musb-hdrc.0.auto: Failed to request rx1.
[ 4.468852] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517
[ 4.477588] 47401b00.usb-phy supply vcc not found, using dummy regulator
[ 4.487541] musb-hdrc musb-hdrc.1.auto: Failed to request rx1.
[ 4.493598] musb-hdrc musb-hdrc.1.auto: musb_init_controller failed with status -517
[ 4.509249] mousedev: PS/2 mouse device common for all mice
[ 4.516263] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[ 4.523974] i2c /dev entries driver
[ 4.529129] sdhci: Secure Digital Host Controller Interface driver
[ 4.535385] sdhci: Copyright(c) Pierre Ossman
[ 4.540152] omap_hsmmc 48060000.mmc: Got CD GPIO
[ 4.663441] Synopsys Designware Multimedia Card Interface Driver
[ 4.669681] sdhci-pltfm: SDHCI platform and OF driver helper
[ 4.679557] ledtrig-cpu: registered to indicate activity on CPUs
[ 4.685791] hidraw: raw HID events driver (C) Jiri Kosina
[ 4.691637] usbcore: registered new interface driver usbhid
[ 4.697288] usbhid: USB HID core driver
[ 4.701361] ashmem: initialized
[ 4.704828] remoteproc0: wkup_m3 is available
[ 4.709332] remoteproc0: Note: remoteproc is still under development and considered experimental.
[ 4.718356] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[ 4.733879] NET: Registered protocol family 10
[ 4.739369] mip6: Mobile IPv6
[ 4.742363] NET: Registered protocol family 17
[ 4.746962] Key type dns_resolver registered
[ 4.751248] mpls_gso: MPLS GSO support
[ 4.755215] omap_voltage_late_init: Voltage driver support not added
[ 4.761746] ThumbEE CPU extension supported.
[ 4.766095] Registering SWP/SWPB emulation handler
[ 4.771625] registered taskstats version 1
[ 4.775891] zswap: loaded using pool lzo/zbud
[ 4.781916] Btrfs loaded, crc32c=crc32c-generic
[ 4.787607] Key type encrypted registered
[ 4.797266] mmc1: new high speed MMC card at address 0001
[ 4.803398] mmcblk1: mmc1:0001 MMC04G 3.60 GiB
[ 4.808205] mmcblk1boot0: mmc1:0001 MMC04G partition 1 2.00 MiB
[ 4.814402] mmcblk1boot1: mmc1:0001 MMC04G partition 2 2.00 MiB
[ 4.821484] mmcblk1: p1
[ 4.835316] input: tps65217_pwr_but as /devices/platform/ocp/44e0b000.i2c/i2c-0/0-0024/tps65217-pwrbutton/input/input0
[ 4.846510] tps65217 0-0024: TPS65217 ID 0xe version 1.2
[ 4.852265] at24 0-0050: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 4.859411] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 4.866287] at24 2-0054: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 4.873537] at24 2-0055: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 4.880715] at24 2-0056: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 4.887921] at24 2-0057: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 4.894864] omap_i2c 4819c000.i2c: bus 2 rev0.11 at 100 kHz
[ 4.901251] remoteproc0: powering up wkup_m3
[ 4.905706] remoteproc0: Booting fw image am335x-pm-firmware.elf, size 217148
[ 4.913200] remoteproc0: remote processor wkup_m3 is now up
[ 4.918891] wkup_m3_ipc 44e11324.wkup_m3_ipc: CM3 Firmware Version = 0x192
[ 4.930295] bone_capemgr bone_capemgr: Baseboard: 'A335BNLT,00C0,3214BBBK3605'
[ 4.937587] bone_capemgr bone_capemgr: compatible-baseboard=ti,beaglebone-black - #slots=4
[ 4.974919] bone_capemgr bone_capemgr: slot #0: No cape found
[ 5.003271] bone_capemgr bone_capemgr: slot #1: No cape found
[ 5.033608] bone_capemgr bone_capemgr: slot #2: No cape found
[ 5.063941] bone_capemgr bone_capemgr: slot #3: No cape found
[ 5.069763] bone_capemgr bone_capemgr: initialized OK.
[ 5.082683] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[ 5.088535] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
[ 5.096580] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 5.103415] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 5.110667] usb usb1: Product: MUSB HDRC host driver
[ 5.115658] usb usb1: Manufacturer: Linux 4.9.8-1-ARCH musb-hcd
[ 5.121601] usb usb1: SerialNumber: musb-hdrc.1.auto
[ 5.127231] hub 1-0:1.0: USB hub found
[ 5.131022] hub 1-0:1.0: 1 port detected
[ 5.136901] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800)
[ 5.145600] of_cfs_init
[ 5.148172] of_cfs_init: OK
[ 5.151434] PM: Hibernation image not present or could not be loaded.
[ 5.152805] EXT4-fs (mmcblk1p1): couldn't mount as ext3 due to feature incompatibilities
[ 5.161638] EXT4-fs (mmcblk1p1): couldn't mount as ext2 due to feature incompatibilities
[ 5.627080] EXT4-fs (mmcblk1p1): recovery complete
[ 5.632563] EXT4-fs (mmcblk1p1): mounted filesystem with ordered data mode. Opts: (null)
[ 5.640782] VFS: Mounted root (ext4 filesystem) on device 179:9.
[ 5.647606] devtmpfs: mounted
[ 5.651814] Freeing unused kernel memory: 1024K (c0c00000 - c0d00000)
[ 5.909317] systemd[1]: System time before build time, advancing clock.
[ 5.966827] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 6.006863] systemd[1]: systemd 232 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[ 6.025556] systemd[1]: Detected architecture arm.
[ 6.054114] systemd[1]: Set hostname to <exeon>.
[ 6.491288] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 6.547973] systemd[1]: Listening on Journal Socket.
[ 6.583857] systemd[1]: Listening on udev Control Socket.
[ 6.613645] systemd[1]: Listening on udev Kernel Socket.
[ 6.643468] systemd[1]: Reached target Remote File Systems.
[ 6.674578] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 6.713774] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 7.244693] systemd-journald[97]: File /var/log/journal/7aba942d55234d1796d197fdcf897c98/system.journal corrupted or uncleanly shut down, renaming and replacing.
[ 8.054197] systemd-journald[97]: Received request to flush runtime journal from PID 1
[ 9.595064] bone_capemgr bone_capemgr: part_number 'BB-I2C1', version 'N/A'
[ 9.602081] bone_capemgr bone_capemgr: slot #4: override
[ 9.607513] bone_capemgr bone_capemgr: Using override eeprom data at slot 4
[ 9.614527] bone_capemgr bone_capemgr: slot #4: 'Override Board Name,00A0,Override Manuf,BB-I2C1'
[ 9.747117] omap_i2c 4802a000.i2c: bus 1 rev0.11 at 100 kHz
[ 9.768777] bone_capemgr bone_capemgr: slot #4: dtbo 'BB-I2C1-00A0.dtbo' loaded; overlay id #0
[ 9.800547] bone_capemgr bone_capemgr: part_number 'BB-UART1', version 'N/A'
[ 9.826900] bone_capemgr bone_capemgr: slot #5: override
[ 9.832261] bone_capemgr bone_capemgr: Using override eeprom data at slot 5
[ 9.839330] bone_capemgr bone_capemgr: slot #5: 'Override Board Name,00A0,Override Manuf,BB-UART1'
[ 9.918342] 48022000.serial: ttyS1 at MMIO 0x48022000 (irq = 190, base_baud = 3000000) is a 8250
[ 9.968049] bone_capemgr bone_capemgr: slot #5: dtbo 'BB-UART1-00A0.dtbo' loaded; overlay id #1
[ 10.021713] bone_capemgr bone_capemgr: part_number 'BB-UART2', version 'N/A'
[ 10.049705] bone_capemgr bone_capemgr: slot #6: override
[ 10.055133] bone_capemgr bone_capemgr: Using override eeprom data at slot 6
[ 10.062130] bone_capemgr bone_capemgr: slot #6: 'Override Board Name,00A0,Override Manuf,BB-UART2'
[ 10.230060] 48024000.serial: ttyS2 at MMIO 0x48024000 (irq = 191, base_baud = 3000000) is a 8250
[ 10.337605] bone_capemgr bone_capemgr: slot #6: dtbo 'BB-UART2-00A0.dtbo' loaded; overlay id #2
[ 10.413248] bone_capemgr bone_capemgr: part_number 'BB-UART4', version 'N/A'
[ 10.420438] bone_capemgr bone_capemgr: slot #7: override
[ 10.425824] bone_capemgr bone_capemgr: Using override eeprom data at slot 7
[ 10.432821] bone_capemgr bone_capemgr: slot #7: 'Override Board Name,00A0,Override Manuf,BB-UART4'
[ 10.646226] 481a8000.serial: ttyS4 at MMIO 0x481a8000 (irq = 192, base_baud = 3000000) is a 8250
[ 10.693725] bone_capemgr bone_capemgr: slot #7: dtbo 'BB-UART4-00A0.dtbo' loaded; overlay id #3
[ 10.741709] bone_capemgr bone_capemgr: part_number 'BB-ADC', version 'N/A'
[ 10.769423] bone_capemgr bone_capemgr: slot #8: override
[ 10.774872] bone_capemgr bone_capemgr: Using override eeprom data at slot 8
[ 10.781869] bone_capemgr bone_capemgr: slot #8: 'Override Board Name,00A0,Override Manuf,BB-ADC'
[ 10.875897] bone_capemgr bone_capemgr: slot #8: dtbo 'BB-ADC-00A0.dtbo' loaded; overlay id #4
[ 10.946886] rtc-ds1307 1-0068: rtc core: registered ds1307 as rtc1
[ 10.953187] rtc-ds1307 1-0068: 56 bytes nvram
[ 10.990973] i2c i2c-1: new_device: Instantiated device ds1307 at 0x68
[ 12.523800] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 13.736408] tda998x 0-0070: found TDA19988
[ 13.839828] tilcdc 4830e000.lcdc: bound 0-0070 (ops tda998x_ops [tda998x])
[ 13.867058] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 13.873791] [drm] No driver support for vblank timestamp query.
[ 13.976080] tilcdc 4830e000.lcdc: No connectors reported connected with modes
[ 14.161433] [drm] Cannot find any crtc or sizes - going 1024x768
[ 14.393557] Console: switching to colour frame buffer device 128x48
[ 14.541408] tilcdc 4830e000.lcdc: fb0: frame buffer device
[ 14.656482] [drm] Initialized tilcdc 1.0.0 20121205 on minor 0
[ 14.743969] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20
[ 14.823701] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[ 14.979161] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[ 15.095210] omap-aes 53500000.aes: will run requests pump with realtime priority
[ 15.325748] net eth0: initializing cpsw version 1.12 (0)
[ 15.423918] SMSC LAN8710/LAN8720 4a101000.mdio:00: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=4a101000.mdio:00, irq=-1)
[ 15.471334] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 15.662031] random: crng init done
[ 15.852263] asoc-simple-card sound: hdmi-hifi.0 <-> 48038000.mcasp mapping ok
[ 15.923372] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[ 15.981698] ti_am3359-tscadc 44e0d000.tscadc: chan 0 step_avg truncating to 16
[ 15.989067] ti_am3359-tscadc 44e0d000.tscadc: chan 1 step_avg truncating to 16
[ 15.996358] ti_am3359-tscadc 44e0d000.tscadc: chan 2 step_avg truncating to 16
[ 16.003629] ti_am3359-tscadc 44e0d000.tscadc: chan 3 step_avg truncating to 16
[ 16.010881] ti_am3359-tscadc 44e0d000.tscadc: chan 4 step_avg truncating to 16
[ 16.018154] ti_am3359-tscadc 44e0d000.tscadc: chan 5 step_avg truncating to 16
[ 16.025416] ti_am3359-tscadc 44e0d000.tscadc: chan 6 step_avg truncating to 16
[ 16.214495] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 16.221241] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 16.228434] usb 1-1: Product: HUAWEI Mobile
[ 16.232633] usb 1-1: Manufacturer: HUAWEI Technology
[ 16.271303] usb-storage 1-1:1.0: USB Mass Storage device detected
[ 16.278849] scsi host0: usb-storage 1-1:1.0
[ 16.288415] usb-storage 1-1:1.1: USB Mass Storage device detected
[ 16.294939] scsi host1: usb-storage 1-1:1.1
[ 17.346733] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 17.357534] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 17.381951] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 17.504114] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[ 17.527529] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 17.583955] sr 0:0:0:0: [sr0] scsi-1 drive
[ 17.588110] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 17.634947] sr 0:0:0:0: Attached scsi CD-ROM sr0
[ 17.781948] usb 1-1: USB disconnect, device number 2
[ 28.483282] usb 1-1: new high-speed USB device number 3 using musb-hdrc
[ 28.664475] usb 1-1: New USB device found, idVendor=12d1, idProduct=1446
[ 28.671215] usb 1-1: New USB device strings: Mfr=2, Product=1, SerialNumber=0
[ 28.678406] usb 1-1: Product: HUAWEI Mobile
[ 28.682605] usb 1-1: Manufacturer: HUAWEI Technology
[ 28.698804] usb-storage 1-1:1.0: USB Mass Storage device detected
[ 28.706004] scsi host0: usb-storage 1-1:1.0
[ 28.712181] usb-storage 1-1:1.1: USB Mass Storage device detected
[ 28.722475] scsi host1: usb-storage 1-1:1.1
[ 29.746852] scsi 1:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
[ 29.757606] scsi 0:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
[ 29.776252] sd 1:0:0:0: [sda] Attached SCSI removable disk
[ 29.799461] sr 0:0:0:0: [sr0] scsi-1 drive
[ 29.809445] sr 0:0:0:0: Attached scsi CD-ROM sr0


Offline Profile
PostPosted: Sun Feb 12, 2017 2:37 pm Reply with quote
Posts: 1156Location: Koh Samui, THJoined: Wed Jul 11, 2012 3:14 pm
I have a sneaky feeling that your problem is the same as:
viewtopic.php?f=3&t=2445


Offline Profile
PostPosted: Sun Feb 12, 2017 2:54 pm Reply with quote
Site AdminPosts: 6361Joined: Sat Nov 03, 2007 12:30 am
darzoz wrote:
Hi Josh, I also tried that with different values at:

SetStorageDelay=8

... no effect. Still get the same behavior.

You know that this is only activated during an automated run of usb_modeswitch (wrapper and all), right?
If in doubt, check the attribute directly: /sys/module/usb_storage/parameters/delay_use

Regarding embedded systems and USB ports - I would always check what happens with a powered USB hub involved.


Offline Profile
PostPosted: Sun Feb 12, 2017 3:06 pm Reply with quote
Posts: 1156Location: Koh Samui, THJoined: Wed Jul 11, 2012 3:14 pm
The auto after musb-hdrc means the port can work as host or as otg and this auto detection which causes a moment of no usb vbus voltage makes the modem coming back with its initial id.
The usb_modeswitch log showed a successful switching so it should not be a usb_modeswitch fault..

I found an interesting page on the net, see bottom of page describing how you can change auto to host or otg.
You want pure host mode and not this flakey auto mode..

https://wiki.maemo.org/USB_host_mode


Offline Profile
PostPosted: Sun Feb 12, 2017 3:17 pm Reply with quote
Posts: 5Joined: Sun Feb 12, 2017 12:22 pm
Hey ... solved!!

You both (Josh/LOM) pointed in the right direction: it's some sort of power problem. For any reason it was not showing up in previous kernels, but it does in versions above 4. I guess some other things changed in the Arch image as well... so maybe the problem comes from the DT.

Following LOM's link I checked the mode of musb, but was already configured as host:

[root@bbb ]# cat /sys/bus/platform/devices/musb-hdrc.1.auto/driver/musb-hdrc.1.auto/mode
a_host

There's some info specific to musb on TI processors here:

http://processors.wiki.ti.com/index.php ... 29_testing

Just in case anyone needs it.

Then I just connected the modem through a usb hub and it worked inmediately. The funny thing is I even didn't have time to connect external power to the hub...

Thanks a lot for your time.


Offline Profile
PostPosted: Sun Feb 12, 2017 3:51 pm Reply with quote
Posts: 1156Location: Koh Samui, THJoined: Wed Jul 11, 2012 3:14 pm
darzoz wrote:

Then I just connected the modem through a usb hub and it worked inmediately. The funny thing is I even didn't have time to connect external power to the hub...



That tells me that it is not your dongle drawing to much current from the port causing the problem, it is the short disappearance of the dongle from the port that triggers the error. (an error in the vbus detector of musb)
The hub doesn't disappear from musb.hdrc port when the dongle disappears from the external hub port for a short while when it gets mode switched.
So you don't need a powered hub just a plain simple one..


Offline Profile
PostPosted: Sun Feb 12, 2017 4:19 pm Reply with quote
Posts: 5Joined: Sun Feb 12, 2017 12:22 pm
Makes sense. Can you think of any software solution/workaround? I mean not to need a hub.

Or just tell me where should I continue digging... As I mentioned it was working in an older system. It has to be something silly and easy to solve, once you find where the root of the problem is.


Offline Profile
PostPosted: Sun Feb 12, 2017 4:41 pm Reply with quote
Posts: 1156Location: Koh Samui, THJoined: Wed Jul 11, 2012 3:14 pm
The musb-hdrc driver has most likely changed between the older and newer linux versions, you should do a bug report to the musb maintainer (see the other thread here that I linked to) on the linux_usb mailing list.

The vbus detecting problem has to be solved in the driver so I cant see anything you can currently do to get rid of the hub, it does what Toto once sang: Hold the line :wink:


Offline Profile

Display posts from previous:  Sort by:

All times are UTC + 1 hour [ DST ]
Page 1 of 1
12 posts
Users browsing this forum: No registered users and 2 guests
Search for:
Post new topic  Reply to topic
Jump to:  
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot post attachments in this forum