Re: Slow mobile broadband detection

["D. Guandalino" <guandalino gmail com>]

2011/6/30 Dan Williams <dcbw redhat com>:
> On Sun, 2011-06-19 at 15:48 +0200, D. Guandalino wrote:
>> Hi, any news?
>
> If you're able to test a kernel patch, try this for the option driver.
> If that works for you I'll submit it upstream.

I have recompiled the module with the patch applied and now the whole
process takes less than 60 secs!

Dan, many THANKS for your support.


Jul  5 23:39:11 tk kernel: [ 9915.954393] usb 2-1.1: new high speed
USB device using ehci_hcd and address 6
Jul  5 23:39:11 tk kernel: [ 9916.394420] scsi12 : usb-storage 2-1.1:1.0
Jul  5 23:39:12 tk usb_modeswitch: switching 19d2:2000
(ONDA,Incorporated: ONDA WCDMA Technologies MSM)
Jul  5 23:39:14 tk kernel: [ 9919.893238] usb 2-1.1: USB disconnect, address 6
Jul  5 23:39:20 tk kernel: [ 9924.951767] usb 2-1.1: new high speed
USB device using ehci_hcd and address 7
Jul  5 23:39:20 tk kernel: [ 9925.391875] scsi13 : usb-storage 2-1.1:1.2
Jul  5 23:39:20 tk kernel: [ 9925.427904] USB Serial support
registered for GSM modem (1-port)
Jul  5 23:39:20 tk kernel: [ 9925.428116] option 2-1.1:1.0: GSM modem
(1-port) converter detected
Jul  5 23:39:20 tk kernel: [ 9925.428276] usb 2-1.1: GSM modem
(1-port) converter now attached to ttyUSB0
Jul  5 23:39:20 tk kernel: [ 9925.428294] option 2-1.1:1.1: GSM modem
(1-port) converter detected
Jul  5 23:39:20 tk kernel: [ 9925.428376] usb 2-1.1: GSM modem
(1-port) converter now attached to ttyUSB1
Jul  5 23:39:20 tk kernel: [ 9925.428397] option 2-1.1:1.3: GSM modem
(1-port) converter detected
Jul  5 23:39:20 tk kernel: [ 9925.428509] usb 2-1.1: GSM modem
(1-port) converter now attached to ttyUSB2
Jul  5 23:39:20 tk kernel: [ 9925.428571] usbcore: registered new
interface driver option
Jul  5 23:39:20 tk kernel: [ 9925.428573] option: v0.7.2fix:USB Driver
for GSM modems
Jul  5 23:39:20 tk modem-manager[828]: <info>  (ttyUSB0) opening serial port...
Jul  5 23:39:20 tk modem-manager[828]: <info>  (ttyUSB1) opening serial port...
Jul  5 23:39:20 tk modem-manager[828]: <info>  (ttyUSB2) opening serial port...
Jul  5 23:39:21 tk kernel: [ 9926.531483] scsi 13:0:0:0: Direct-Access
    ONDA     MMC Storage      2.31 PQ: 0 ANSI: 2
Jul  5 23:39:21 tk kernel: [ 9926.531896] sd 13:0:0:0: Attached scsi
generic sg2 type 0
Jul  5 23:39:21 tk usb_modeswitch: switched to 19d2:0037
(ONDA,Incorporated: ONDA WCDMA Technologies MSM)
Jul  5 23:39:21 tk kernel: [ 9926.731266] sd 13:0:0:0: [sdc] Attached
SCSI removable disk
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ttyUSB1) closing serial port...
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ttyUSB1) serial port closed
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ttyUSB2) closing serial port...
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ttyUSB2) serial port closed
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ZTE): GSM modem
/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1 claimed port
ttyUSB1
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ttyUSB2) opening serial port...
Jul  5 23:39:29 tk modem-manager[828]: <info>  (ZTE): GSM modem
/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1 claimed port
ttyUSB2
Jul  5 23:39:31 tk modem-manager[828]: <info>  (ttyUSB2) closing serial port...
Jul  5 23:39:31 tk modem-manager[828]: <info>  (ttyUSB2) serial port closed
Jul  5 23:39:53 tk modem-manager[828]: <info>  (ttyUSB0) closing serial port...
Jul  5 23:39:53 tk modem-manager[828]: <info>  (ttyUSB0) serial port closed
Jul  5 23:39:53 tk modem-manager[828]: <info>  (ttyUSB0) opening serial port...
Jul  5 23:39:56 tk modem-manager[828]: <info>  (ttyUSB0) closing serial port...
Jul  5 23:39:56 tk modem-manager[828]: <info>  (ttyUSB0) serial port closed
Jul  5 23:39:56 tk modem-manager[828]: <info>  (ZTE): GSM modem
/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1 claimed port
ttyUSB0
Jul  5 23:39:56 tk NetworkManager[822]: <warn> (ttyUSB2): failed to
look up interface index
Jul  5 23:39:56 tk NetworkManager[822]: <info> WWAN now disabled by
management service
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): new GSM
device (driver: 'option1' ifindex: -1)
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): exported as
/org/freedesktop/NetworkManager/Devices/3
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): now managed
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): device state
change: 1 -> 2 (reason 2)
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): deactivating
device (reason: 2).
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): device state
change: 2 -> 3 (reason 0)
Jul  5 23:39:56 tk NetworkManager[822]: <info> Activation (ttyUSB2)
starting connection 'MOBILE BROADBAND'
Jul  5 23:39:56 tk NetworkManager[822]: <info> (ttyUSB2): device state
change: 3 -> 4 (reason 0)
Jul  5 23:39:56 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 1 of 5 (Device Prepare) scheduled...
Jul  5 23:39:56 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 1 of 5 (Device Prepare) started...
Jul  5 23:39:56 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 1 of 5 (Device Prepare) complete.
Jul  5 23:39:56 tk modem-manager[828]: <info>  (ttyUSB2) opening serial port...
Jul  5 23:39:56 tk modem-manager[828]: <info>  Modem
/org/freedesktop/ModemManager/Modems/1: state changed (disabled ->
enabling)
Jul  5 23:39:57 tk modem-manager[828]: <info>  (ttyUSB1) opening serial port...
Jul  5 23:39:58 tk modem-manager[828]: <info>  Modem
/org/freedesktop/ModemManager/Modems/1: state changed (enabling ->
enabled)
Jul  5 23:39:58 tk NetworkManager[822]: <info> WWAN now enabled by
management service
Jul  5 23:39:58 tk modem-manager[828]: <info>  Modem
/org/freedesktop/ModemManager/Modems/1: state changed (enabled ->
registered)
Jul  5 23:40:00 tk modem-manager[828]: <info>  Modem
/org/freedesktop/ModemManager/Modems/1: state changed (registered ->
connecting)
Jul  5 23:40:00 tk modem-manager[828]: <info>  Modem
/org/freedesktop/ModemManager/Modems/1: state changed (connecting ->
connected)
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 2 of 5 (Device Configure) scheduled...
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 2 of 5 (Device Configure) starting...
Jul  5 23:40:00 tk NetworkManager[822]: <info> (ttyUSB2): device state
change: 4 -> 5 (reason 0)
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 2 of 5 (Device Configure) successful.
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 3 of 5 (IP Configure Start) scheduled.
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 2 of 5 (Device Configure) complete.
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 3 of 5 (IP Configure Start) started...
Jul  5 23:40:00 tk NetworkManager[822]: <info> (ttyUSB2): device state
change: 5 -> 7 (reason 0)
Jul  5 23:40:00 tk NetworkManager[822]: <info> starting PPP connection
Jul  5 23:40:00 tk NetworkManager[822]: <info> pppd started with pid 7931
Jul  5 23:40:00 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 3 of 5 (IP Configure Start) complete.
Jul  5 23:40:00 tk pppd[7931]: Plugin
/usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
Jul  5 23:40:00 tk pppd[7931]: pppd 2.4.5 started by root, uid 0
Jul  5 23:40:00 tk pppd[7931]: Using interface ppp0
Jul  5 23:40:00 tk pppd[7931]: Connect: ppp0 <--> /dev/ttyUSB2
Jul  5 23:40:00 tk NetworkManager[822]:    SCPlugin-Ifupdown: devices
added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Jul  5 23:40:00 tk NetworkManager[822]:    SCPlugin-Ifupdown: device
added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown
configuration found.
Jul  5 23:40:00 tk pppd[7931]: CHAP authentication succeeded
Jul  5 23:40:00 tk pppd[7931]: CHAP authentication succeeded
Jul  5 23:40:07 tk pppd[7931]: Could not determine remote IP address:
defaulting to xxx.xxx.xxx.xxx
Jul  5 23:40:07 tk pppd[7931]: local  IP address xxx.xxx.xxx.xxx
Jul  5 23:40:07 tk pppd[7931]: remote IP address xxx.xxx.xxx.xxx
Jul  5 23:40:07 tk pppd[7931]: primary   DNS address xxx.xxx.xxx.xxx
Jul  5 23:40:07 tk pppd[7931]: secondary DNS address xxx.xxx.xxx.xxx
Jul  5 23:40:07 tk NetworkManager[822]: <info> PPP manager(IP Config
Get) reply received.
Jul  5 23:40:07 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 4 of 5 (IP4 Configure Get) scheduled...
Jul  5 23:40:07 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 4 of 5 (IP4 Configure Get) started...
Jul  5 23:40:07 tk NetworkManager[822]: <info> Scheduling stage 5
Jul  5 23:40:07 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 5 of 5 (IP Configure Commit) scheduled...
Jul  5 23:40:07 tk NetworkManager[822]: <info> Done scheduling stage 5
Jul  5 23:40:07 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 4 of 5 (IP4 Configure Get) complete.
Jul  5 23:40:07 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 5 of 5 (IP Configure Commit) started...
Jul  5 23:40:08 tk NetworkManager[822]: <info> (ttyUSB2): device state
change: 7 -> 8 (reason 0)
Jul  5 23:40:08 tk NetworkManager[822]: <info> Policy set 'MOBILE
BROADBAND' (ppp0) as default for IPv4 routing and DNS.
Jul  5 23:40:08 tk NetworkManager[822]: <info> Activation (ttyUSB2)
successful, device activated.
Jul  5 23:40:08 tk NetworkManager[822]: <info> Activation (ttyUSB2)
Stage 5 of 5 (IP Configure Commit) complete.