Re: Hello, please help with my 3G device

[Emfox Zhou <emfoxzhou gmail com>]

On Mon, Jul 16, 2012 at 12:40 AM, Aleksander Morgado
<aleksander lanedo com> wrote:
> Hey,
>
>>>
>>>
>>> The Huawei plugin always starts probing USB interface 0; and it seems
>>> that none of the 3 ttyACM ports given has usbif 0, so never starts
>>> probing the other ports. The plugin should possibly wait up to N seconds
>>> for usbif 0 to appear, and keep on probing normally if it doesn't appear
>>> in that time.
>>>
>>> Will try to prepare a patch for that.
>>
>> Great! cannot wait to try :)
>>
>
> Attached is a patch to apply on top of the MM_05 branch of ModemManager:
>   $> git clone git://anongit.freedesktop.org/ModemManager/ModemManager
>   $> cd ModemManager
>   $> git checkout -b MM_05 origin/MM_05
>   $> git am /path/to/the/patch.diff
>   $> ./autogen.sh --prefix=/usr --sysconfdir=/etc --localstatedir=/var
>   $> make
>   $> sudo make install
>
> It is not a perfect solution, but should do the trick. I'll find a
> better fix for git master/0.7; as I do have some probing improvements
> ready there which would help quite a lot also in this case.
>
> Let me know if it worked or not, as I don't have any Huawei modem around
> to play with. Please attach debug logs in any case.

Hello, it partially works.

I can find the device in nm now, but when try to connect to 3g net, it fails.

-- 
Emfox Zhou

GnuPG Public Key: 0xF7142EC2

emfox@emfox:~$ sudo modem-manager --debug
modem-manager[388]: <info>  [1342392224.774330] [main.c:167] main(): ModemManager (version 0.5.3.0) starting...
modem-manager[388]: <info>  [1342392224.776724] [mm-manager.c:120] load_plugin(): Loaded plugin Nokia
modem-manager[388]: <info>  [1342392224.777000] [mm-manager.c:120] load_plugin(): Loaded plugin Option
modem-manager[388]: <info>  [1342392224.777199] [mm-manager.c:120] load_plugin(): Loaded plugin Linktop
modem-manager[388]: <info>  [1342392224.777391] [mm-manager.c:120] load_plugin(): Loaded plugin X22X
modem-manager[388]: <info>  [1342392224.777587] [mm-manager.c:120] load_plugin(): Loaded plugin Huawei
modem-manager[388]: <info>  [1342392224.777796] [mm-manager.c:120] load_plugin(): Loaded plugin Option High-Speed
modem-manager[388]: <info>  [1342392224.777994] [mm-manager.c:120] load_plugin(): Loaded plugin Novatel
modem-manager[388]: <info>  [1342392224.778192] [mm-manager.c:120] load_plugin(): Loaded plugin ZTE
modem-manager[388]: <info>  [1342392224.778385] [mm-manager.c:120] load_plugin(): Loaded plugin Gobi
modem-manager[388]: <info>  [1342392224.778580] [mm-manager.c:120] load_plugin(): Loaded plugin AnyData
modem-manager[388]: <info>  [1342392224.778776] [mm-manager.c:120] load_plugin(): Loaded plugin Wavecom
modem-manager[388]: <info>  [1342392224.778994] [mm-manager.c:120] load_plugin(): Loaded plugin Samsung
modem-manager[388]: <info>  [1342392224.779215] [mm-manager.c:120] load_plugin(): Loaded plugin Longcheer
modem-manager[388]: <info>  [1342392224.779426] [mm-manager.c:120] load_plugin(): Loaded plugin Generic
modem-manager[388]: <info>  [1342392224.779618] [mm-manager.c:120] load_plugin(): Loaded plugin MotoC
modem-manager[388]: <info>  [1342392224.779823] [mm-manager.c:120] load_plugin(): Loaded plugin Sierra
modem-manager[388]: <info>  [1342392224.780025] [mm-manager.c:120] load_plugin(): Loaded plugin Ericsson MBM
modem-manager[388]: <info>  [1342392224.780222] [mm-manager.c:120] load_plugin(): Loaded plugin SimTech
modem-manager[388]: <debug> [1342392224.783039] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM0) deferring support check
modem-manager[388]: <debug> [1342392224.784091] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM1) deferring support check
modem-manager[388]: <debug> [1342392224.785192] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM2) deferring support check
modem-manager[388]: <debug> [1342392224.785392] [mm-manager.c:809] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
modem-manager[388]: <debug> [1342392224.785865] [mm-manager.c:809] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
modem-manager[388]: <debug> [1342392224.786047] [mm-manager.c:809] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
modem-manager[388]: <debug> [1342392224.786342] [mm-manager.c:809] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
modem-manager[388]: <debug> [1342392227.787127] [mm-manager.c:457] supports_defer_timeout(): (ttyACM0): re-checking support...
modem-manager[388]: <debug> [1342392227.787531] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM0) deferring support check
modem-manager[388]: <debug> [1342392227.787559] [mm-manager.c:457] supports_defer_timeout(): (ttyACM1): re-checking support...
modem-manager[388]: <debug> [1342392227.787805] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM1) deferring support check
modem-manager[388]: <debug> [1342392227.787828] [mm-manager.c:457] supports_defer_timeout(): (ttyACM2): re-checking support...
modem-manager[388]: <debug> [1342392227.788070] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM2) deferring support check
modem-manager[388]: <debug> [1342392230.791255] [mm-manager.c:457] supports_defer_timeout(): (ttyACM0): re-checking support...
modem-manager[388]: <debug> [1342392230.792233] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM0) deferring support check
modem-manager[388]: <debug> [1342392230.792308] [mm-manager.c:457] supports_defer_timeout(): (ttyACM1): re-checking support...
modem-manager[388]: <debug> [1342392230.793048] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM1) deferring support check
modem-manager[388]: <debug> [1342392230.793114] [mm-manager.c:457] supports_defer_timeout(): (ttyACM2): re-checking support...
modem-manager[388]: <debug> [1342392230.793872] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM2) deferring support check
modem-manager[388]: <debug> [1342392233.796083] [mm-manager.c:457] supports_defer_timeout(): (ttyACM0): re-checking support...
modem-manager[388]: <debug> [1342392233.797036] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM0) deferring support check
modem-manager[388]: <debug> [1342392233.797108] [mm-manager.c:457] supports_defer_timeout(): (ttyACM1): re-checking support...
modem-manager[388]: <debug> [1342392233.797861] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM1) deferring support check
modem-manager[388]: <debug> [1342392233.797928] [mm-manager.c:457] supports_defer_timeout(): (ttyACM2): re-checking support...
modem-manager[388]: <debug> [1342392233.798662] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyACM2) deferring support check
modem-manager[388]: <debug> [1342392236.799720] [mm-manager.c:457] supports_defer_timeout(): (ttyACM0): re-checking support...
modem-manager[388]: <debug> [1342392236.800692] [mm-plugin-huawei.c:223] supports_port(): (Huawei): no longer waiting for usbif 0, will launch probing in interface (tty/ttyACM0)
modem-manager[388]: <info>  [1342392236.801316] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[388]: <debug> [1342392236.803247] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[388]: <debug> [1342392236.803338] [mm-manager.c:457] supports_defer_timeout(): (ttyACM1): re-checking support...
modem-manager[388]: <debug> [1342392236.804296] [mm-plugin-huawei.c:223] supports_port(): (Huawei): no longer waiting for usbif 0, will launch probing in interface (tty/ttyACM1)
modem-manager[388]: <info>  [1342392236.804809] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM1) opening serial port...
modem-manager[388]: <debug> [1342392236.806105] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
modem-manager[388]: <debug> [1342392236.806303] [mm-manager.c:457] supports_defer_timeout(): (ttyACM2): re-checking support...
modem-manager[388]: <debug> [1342392236.807153] [mm-plugin-huawei.c:223] supports_port(): (Huawei): no longer waiting for usbif 0, will launch probing in interface (tty/ttyACM2)
modem-manager[388]: <info>  [1342392236.807608] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM2) opening serial port...
modem-manager[388]: <debug> [1342392236.809101] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM2) device open count is 1 (open)
modem-manager[388]: <debug> [1342392236.810138] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CREG: 1<CR><LF>'
modem-manager[388]: <debug> [1342392236.811126] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CGREG: 1,"D712","C456"<CR><LF>'
modem-manager[388]: <debug> [1342392236.812094] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CREG: 1,"D712","C456"<CR><LF>'
modem-manager[388]: <debug> [1342392236.813089] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CGREG: 1,"D712","2553"<CR><LF>'
modem-manager[388]: <debug> [1342392236.814095] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CREG: 1,"D712","2553"<CR><LF>'
modem-manager[388]: <debug> [1342392236.816679] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CGREG: 1,"D712","9EFD"<CR><LF><CR><LF>+CREG: 1,"D712","9EFD"<CR><LF>'
modem-manager[388]: <debug> [1342392236.817537] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CGREG: 1,"D712","02B4"<CR><LF>'
modem-manager[388]: <debug> [1342392236.818098] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CREG: 1,"D712","02B4"<CR><LF>'
modem-manager[388]: <debug> [1342392236.819094] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+CGREG: 1,"D712","C604"<CR><LF>'
modem-manager[388]: <debug> [1342392244.141670] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
modem-manager[388]: <info>  [1342392244.141818] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM0) closing serial port...
modem-manager[388]: <info>  [1342392244.159333] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM0) serial port closed
modem-manager[388]: <debug> [1342392244.164899] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
modem-manager[388]: <info>  [1342392244.164975] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM1) closing serial port...
modem-manager[388]: <info>  [1342392244.183366] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM1) serial port closed
modem-manager[388]: <debug> [1342392244.188983] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM2) device open count is 0 (close)
modem-manager[388]: <info>  [1342392244.189150] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM2) closing serial port...
modem-manager[388]: <info>  [1342392244.207340] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM2) serial port closed
modem-manager[388]: <info>  [1342392244.212885] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[388]: <debug> [1342392244.214382] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[388]: <debug> [1342392244.214518] [mm-plugin-base.c:859] try_open(): (ttyACM0): probe requested by plugin 'Generic'
modem-manager[388]: <info>  [1342392244.215295] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM1) opening serial port...
modem-manager[388]: <debug> [1342392244.216346] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
modem-manager[388]: <debug> [1342392244.216471] [mm-plugin-base.c:859] try_open(): (ttyACM1): probe requested by plugin 'Generic'
modem-manager[388]: <info>  [1342392244.217309] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM2) opening serial port...
modem-manager[388]: <debug> [1342392244.218348] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM2) device open count is 1 (open)
modem-manager[388]: <debug> [1342392244.218473] [mm-plugin-base.c:859] try_open(): (ttyACM2): probe requested by plugin 'Generic'
modem-manager[388]: <debug> [1342392244.219393] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>NO CARRIER<CR><LF>'
modem-manager[388]: <debug> [1342392244.219609] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 3: No carrier
modem-manager[388]: <debug> [1342392244.219756] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392244.318446] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>NO CARRIER<CR><LF>'
modem-manager[388]: <debug> [1342392244.318734] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 3: No carrier
modem-manager[388]: <debug> [1342392244.318908] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392244.319270] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GCAP<CR>'
modem-manager[388]: <debug> [1342392244.319371] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+GCAP<CR>'
modem-manager[388]: <debug> [1342392245.126859] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+GCAP'
modem-manager[388]: <debug> [1342392245.126946] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): --> 'AT+GCAP<CR>'
modem-manager[388]: <debug> [1342392245.127366] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.129354] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+GCAP:+FCLASS,+CGSM<CR><LF>'
modem-manager[388]: <debug> [1342392245.130332] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.130403] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+GCAP:+FCLASS,+CGSM<CR><LF>'
modem-manager[388]: <debug> [1342392245.131328] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.827111] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- 'AT+GCAP'
modem-manager[388]: <debug> [1342392245.827271] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
modem-manager[388]: <info>  [1342392245.827315] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM0) closing serial port...
modem-manager[388]: <info>  [1342392245.846408] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM0) serial port closed
modem-manager[388]: <debug> [1342392245.846717] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
modem-manager[388]: <info>  [1342392245.846860] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM1) closing serial port...
modem-manager[388]: <info>  [1342392245.865392] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM1) serial port closed
modem-manager[388]: <debug> [1342392245.865893] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><CR><LF>+GCAP:+FCLASS,+CGSM<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.868685] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyACM0) type primary claimed by /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <info>  [1342392245.869293] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[388]: <debug> [1342392245.870414] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[388]: <debug> [1342392245.870646] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
modem-manager[388]: <debug> [1342392245.870866] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
modem-manager[388]: <info>  [1342392245.871103] [mm-manager.c:564] do_grab_port(): (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1 claimed port ttyACM0
modem-manager[388]: <debug> [1342392245.871355] [mm-manager.c:317] add_modem(): Added modem /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <debug> [1342392245.871553] [mm-manager.c:243] check_export_modem(): (tty/ttyACM0): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <debug> [1342392245.871835] [mm-manager.c:243] check_export_modem(): (tty/ttyACM1): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <debug> [1342392245.872694] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyACM1) type secondary claimed by /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <info>  [1342392245.873276] [mm-manager.c:564] do_grab_port(): (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1 claimed port ttyACM1
modem-manager[388]: <debug> [1342392245.873594] [mm-manager.c:243] check_export_modem(): (tty/ttyACM2): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <debug> [1342392245.873877] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>NO CARRIER<CR><LF>'
modem-manager[388]: <debug> [1342392245.874128] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 3: No carrier
modem-manager[388]: <debug> [1342392245.874550] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GMI<CR>'
modem-manager[388]: <debug> [1342392245.874782] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM2) device open count is 0 (close)
modem-manager[388]: <info>  [1342392245.874966] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM2) closing serial port...
modem-manager[388]: <info>  [1342392245.894012] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM2) serial port closed
modem-manager[388]: <debug> [1342392245.899329] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+GMI'
modem-manager[388]: <debug> [1342392245.899952] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyACM2) type ignored claimed by /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1
modem-manager[388]: <info>  [1342392245.900175] [mm-manager.c:564] do_grab_port(): (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1 claimed port ttyACM2
modem-manager[388]: <debug> [1342392245.900340] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.901349] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HuaWei<CR><LF>'
modem-manager[388]: <debug> [1342392245.903252] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.903435] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GMM<CR>'
modem-manager[388]: <debug> [1342392245.909660] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+GMM'
modem-manager[388]: <debug> [1342392245.910328] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.911346] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>ET8282/6282<CR><LF>'
modem-manager[388]: <debug> [1342392245.912344] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.912509] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GMR<CR>'
modem-manager[388]: <debug> [1342392245.918656] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+GMR'
modem-manager[388]: <debug> [1342392245.919330] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.920329] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>11.101.01.29.00<CR><LF>'
modem-manager[388]: <debug> [1342392245.921328] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.921422] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGMI<CR>'
modem-manager[388]: <debug> [1342392245.928582] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+CGMI'
modem-manager[388]: <debug> [1342392245.929334] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.932163] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HuaWei<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.932299] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGMM<CR>'
modem-manager[388]: <debug> [1342392245.939446] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+CGMM'
modem-manager[388]: <debug> [1342392245.940362] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.941332] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>ET8282/6282<CR><LF>'
modem-manager[388]: <debug> [1342392245.942330] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.942398] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGMR<CR>'
modem-manager[388]: <debug> [1342392245.953314] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+CGMR'
modem-manager[388]: <debug> [1342392245.954331] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.955333] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>11.101.01.29.00<CR><LF>'
modem-manager[388]: <debug> [1342392245.956328] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.956424] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATI<CR>'
modem-manager[388]: <debug> [1342392245.959541] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'ATI'
modem-manager[388]: <debug> [1342392245.960333] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.963477] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>Manufacturer: HuaWei<CR><LF>Model: ET8282/6282<CR><LF>Revision: 11.101.01.29.00<CR><LF>IMEI: 860098000018930<CR><LF>+CGAP: +FCLASS,+CGSM<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.963659] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATI1<CR>'
modem-manager[388]: <debug> [1342392245.967817] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'ATI1'
modem-manager[388]: <debug> [1342392245.968332] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.969334] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.969474] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GSN<CR>'
modem-manager[388]: <debug> [1342392245.975660] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+GSN'
modem-manager[388]: <debug> [1342392245.976332] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.977329] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>860098000018930'
modem-manager[388]: <debug> [1342392245.978328] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.978463] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
modem-manager[388]: <debug> [1342392245.985656] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+CGSN'
modem-manager[388]: <debug> [1342392245.986344] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.987342] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>860098000018930'
modem-manager[388]: <debug> [1342392245.988330] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.988473] [mm-modem-helpers.c:920] mm_create_device_identifier(): Device ID source '000012d100001d09Manufacturer:HuaWeiModel:ET8282/6282Revision:11.101.01.29.00IMEI:860098000018930+CGAP:+FCLASS,+CGSM86009800001893011.101.01.29.00ET8282/6282HuaWei'
modem-manager[388]: <debug> [1342392245.988555] [mm-modem-helpers.c:921] mm_create_device_identifier(): Device ID '90fc8faabc63cd74fcc92d939eb04f1c5585ea03'
modem-manager[388]: <debug> [1342392245.988644] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
modem-manager[388]: <debug> [1342392245.988715] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATE0<CR>'
modem-manager[388]: <debug> [1342392245.993266] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'ATE'
modem-manager[388]: <debug> [1342392245.993341] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '0'
modem-manager[388]: <debug> [1342392245.994334] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[388]: <debug> [1342392245.995359] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392245.995533] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
modem-manager[388]: <debug> [1342392245.995603] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
modem-manager[388]: <debug> [1342392246.007286] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392246.007627] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
modem-manager[388]: <debug> [1342392246.034397] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF>'
modem-manager[388]: <debug> [1342392246.035351] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392246.035413] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
modem-manager[388]: <debug> [1342392246.035444] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
modem-manager[388]: <debug> [1342392246.035496] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[388]: <debug> [1342392246.102386] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"98680045911190184037"<CR><LF>'
modem-manager[388]: <debug> [1342392246.104359] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392246.104461] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
modem-manager[388]: <info>  [1342392246.104504] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM0) closing serial port...
modem-manager[388]: <info>  [1342392246.122402] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM0) serial port closed
modem-manager[388]: <debug> [1342392246.122796] [mm-generic-gsm.c:507] get_iccid_done(): SIM ID source '89860054191109810473'
modem-manager[388]: <debug> [1342392246.122985] [mm-generic-gsm.c:508] get_iccid_done(): SIM ID '8818376e31233e18e3fe131b18110d48b549e6c0'
modem-manager[388]: <debug> [1342392246.123443] [mm-manager.c:265] check_export_modem(): Exported modem /sys/devices/pci0000:00/0000:00:1d.1/usb6/6-1 as /org/freedesktop/ModemManager/Modems/0
modem-manager[388]: <debug> [1342392246.123753] [mm-manager.c:278] check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): VID 0x12D1 PID 0x1D09 (usb)
modem-manager[388]: <debug> [1342392246.123936] [mm-manager.c:279] check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): data port is ttyACM0
modem-manager[388]: <info>  [1342392288.172318] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[388]: <debug> [1342392288.173737] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[388]: <info>  [1342392288.173824] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
modem-manager[388]: <debug> [1342392288.275826] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATZ E0 V1<CR>'
modem-manager[388]: <debug> [1342392288.285826] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>NO CARRIER<CR><LF>'
modem-manager[388]: <debug> [1342392288.286039] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 3: No carrier
modem-manager[388]: <debug> [1342392288.286198] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATZ E0 V1<CR>'
modem-manager[388]: <debug> [1342392288.304791] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.305077] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATE0<CR>'
modem-manager[388]: <debug> [1342392288.311761] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.311946] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
modem-manager[388]: <debug> [1342392288.323761] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.323942] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATX4 &C1<CR>'
modem-manager[388]: <debug> [1342392288.333776] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.334079] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CFUN=1<CR>'
modem-manager[388]: <debug> [1342392288.346795] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <info>  [1342392288.347123] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM1) opening serial port...
modem-manager[388]: <debug> [1342392288.349934] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
modem-manager[388]: <debug> [1342392288.350319] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+IFC=1,1<CR>'
modem-manager[388]: <debug> [1342392288.365874] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
modem-manager[388]: <debug> [1342392288.366207] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[388]: <debug> [1342392288.366447] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CPMS="ME","ME","ME"<CR>'
modem-manager[388]: <debug> [1342392288.398458] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
modem-manager[388]: <debug> [1342392288.398866] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
modem-manager[388]: <debug> [1342392288.399111] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
modem-manager[388]: <debug> [1342392288.423796] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.424221] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMGF=?<CR>'
modem-manager[388]: <debug> [1342392288.437784] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CMGF: (0,1)<CR><LF>'
modem-manager[388]: <debug> [1342392288.438769] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.439163] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CUSD=1<CR>'
modem-manager[388]: <debug> [1342392288.452768] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.453035] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND=?<CR>'
modem-manager[388]: <debug> [1342392288.468844] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-4)),("service",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1))<CR><LF>'
modem-manager[388]: <debug> [1342392288.469949] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.470351] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CSCS=?<CR>'
modem-manager[388]: <debug> [1342392288.482904] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF>'
modem-manager[388]: <debug> [1342392288.483782] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.484196] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMGF=1<CR>'
modem-manager[388]: <debug> [1342392288.495793] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <info>  [1342392288.496026] [mm-generic-gsm.c:1704] sms_set_format_cb(): (ttyACM0): using text mode for SMS
modem-manager[388]: <debug> [1342392288.496252] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[388]: <debug> [1342392288.515787] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
modem-manager[388]: <debug> [1342392288.516139] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
modem-manager[388]: <debug> [1342392288.516346] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
modem-manager[388]: <debug> [1342392288.535876] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.536347] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
modem-manager[388]: <debug> [1342392288.546840] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[388]: <info>  [1342392288.547834] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> enabled)
modem-manager[388]: <debug> [1342392288.551112] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
modem-manager[388]: <debug> [1342392288.569854] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.570409] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392288.598751] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392288.599724] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.599869] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
modem-manager[388]: <debug> [1342392288.613756] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"D712","9C4C"<CR><LF>'
modem-manager[388]: <debug> [1342392288.613852] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): CS registration state changed: 1
modem-manager[388]: <info>  [1342392288.613962] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabled -> registered)
modem-manager[388]: <debug> [1342392288.615757] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.615836] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
modem-manager[388]: <debug> [1342392288.627806] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.627924] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+CREG=2<CR>'
modem-manager[388]: <debug> [1342392288.628007] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[388]: <debug> [1342392288.628670] [mm-generic-gsm.c:5957] simple_connect(): (ttyACM0): number => "*99***1#"
modem-manager[388]: <debug> [1342392288.628750] [mm-generic-gsm.c:5957] simple_connect(): (ttyACM0): username => "cmnet"
modem-manager[388]: <debug> [1342392288.628806] [mm-generic-gsm.c:5957] simple_connect(): (ttyACM0): password => "cmnet"
modem-manager[388]: <debug> [1342392288.628860] [mm-generic-gsm.c:5957] simple_connect(): (ttyACM0): apn => "cmnet"
modem-manager[388]: <debug> [1342392288.628917] [mm-generic-gsm.c:5957] simple_connect(): (ttyACM0): network_mode => 0
modem-manager[388]: <debug> [1342392288.628972] [mm-generic-gsm.c:5957] simple_connect(): (ttyACM0): allowed_mode => 0
modem-manager[388]: <debug> [1342392288.629038] [mm-generic-gsm.c:5846] simple_state_machine(): (ttyACM0): simple connect state 0
modem-manager[388]: <debug> [1342392288.629161] [mm-generic-gsm.c:5846] simple_state_machine(): (ttyACM0): simple connect state 2
modem-manager[388]: <debug> [1342392288.639837] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.640137] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+CGREG=2<CR>'
modem-manager[388]: <debug> [1342392288.650785] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: '
modem-manager[388]: <debug> [1342392288.651790] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '0,2,"46000",2<CR><LF>'
modem-manager[388]: <debug> [1342392288.653783] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.655247] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.655596] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[388]: <debug> [1342392288.683747] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: '
modem-manager[388]: <debug> [1342392288.684739] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '0,0,"CHINA MOBILE",2<CR><LF>'
modem-manager[388]: <debug> [1342392288.685736] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.685886] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392288.714797] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392288.716736] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.716819] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
modem-manager[388]: <debug> [1342392288.730867] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"D712","9C4C"<CR><LF>'
modem-manager[388]: <debug> [1342392288.730946] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): PS registration state changed: 1
modem-manager[388]: <debug> [1342392288.731773] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.731857] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
modem-manager[388]: <debug> [1342392288.741775] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"D712","9C4C"<CR><LF>'
modem-manager[388]: <debug> [1342392288.743296] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.743396] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
modem-manager[388]: <debug> [1342392288.754744] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"D712","9C4C"<CR><LF>'
modem-manager[388]: <debug> [1342392288.755749] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.755830] [mm-generic-gsm.c:5846] simple_state_machine(): (ttyACM0): simple connect state 4
modem-manager[388]: <debug> [1342392288.755884] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[388]: <debug> [1342392288.777806] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: '
modem-manager[388]: <debug> [1342392288.778788] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '0,2,"46000",2<CR><LF>'
modem-manager[388]: <debug> [1342392288.779791] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.779988] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[388]: <debug> [1342392288.802807] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: '
modem-manager[388]: <debug> [1342392288.803889] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '0,0,"CHINA MOBILE",2<CR><LF>'
modem-manager[388]: <debug> [1342392288.804819] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.805079] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392288.836843] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392288.837786] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.838138] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
modem-manager[388]: <debug> [1342392288.855868] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","CMWAP","0.0.0.0",0,0<CR><LF>+CGDCONT: 2,"IP","cmnet","0.0.0.0",0,0<CR><LF>+CGDCONT: 3,"IP","","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.856271] [mm-generic-gsm.c:5846] simple_state_machine(): (ttyACM0): simple connect state 5
modem-manager[388]: <info>  [1342392288.856666] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
modem-manager[388]: <debug> [1342392288.856857] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATD*99***1***2#<CR>'
modem-manager[388]: <debug> [1342392288.876803] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 3<CR><LF>'
modem-manager[388]: <debug> [1342392288.877032] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 3: Operation not allowed
modem-manager[388]: <debug> [1342392288.877208] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CEER<CR>'
modem-manager[388]: <debug> [1342392288.886841] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CEER: "Cause Select:0 Cause:0"<CR><LF>'
modem-manager[388]: <debug> [1342392288.887811] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <info>  [1342392288.888154] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (connecting -> registered)
modem-manager[388]: <debug> [1342392288.891729] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392288.936805] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392288.937735] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.937861] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392288.973828] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392288.974752] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392288.974900] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392289.010784] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392289.011813] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392289.012010] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392289.046806] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392289.048033] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[388]: <debug> [1342392319.144851] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[388]: <debug> [1342392319.177830] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 4,3,1,1,0,0,0<CR><LF>'
modem-manager[388]: <debug> [1342392319.179714] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'


emfox@emfox:~$ sudo NM_PPP_DEBUG=1 /usr/sbin/NetworkManager --no-daemon
NetworkManager[391]: <info> NetworkManager (version 0.9.4.0) is starting...
NetworkManager[391]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
NetworkManager[391]:    SCPlugin-Ifupdown: init!
NetworkManager[391]:    SCPlugin-Ifupdown: update_system_hostname
NetworkManager[391]:    SCPluginIfupdown: management mode: unmanaged
NetworkManager[391]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/ssb0:0/net/wlan0, iface: wlan0)
NetworkManager[391]:    SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/ssb0:0/net/wlan0, iface: wlan0): no ifupdown configuration found.
NetworkManager[391]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.5/0000:85:00.0/net/eth0, iface: eth0)
NetworkManager[391]:    SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.5/0000:85:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
NetworkManager[391]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
NetworkManager[391]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
NetworkManager[391]:    SCPlugin-Ifupdown: end _init.
NetworkManager[391]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the NetworkManager mailing list.
NetworkManager[391]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
NetworkManager[391]:    Ifupdown: get unmanaged devices count: 0
NetworkManager[391]:    SCPlugin-Ifupdown: (26512176) ... get_connections.
NetworkManager[391]:    SCPlugin-Ifupdown: (26512176) ... get_connections (managed=false): return empty list.
NetworkManager[391]:    keyfile: parsing China Mobile Internet 1 ... 
NetworkManager[391]:    keyfile:     read connection 'China Mobile Internet 1'
NetworkManager[391]:    keyfile: parsing LiangAn Coffee Free Wi-Fi ... 
NetworkManager[391]:    keyfile:     read connection 'LiangAn Coffee Free Wi-Fi'
NetworkManager[391]:    keyfile: parsing emfox ... 
NetworkManager[391]:    keyfile:     read connection 'emfox'
NetworkManager[391]:    Ifupdown: get unmanaged devices count: 0
NetworkManager[391]: <info> modem-manager is now available
NetworkManager[391]: <info> monitoring kernel firmware directory '/lib/firmware'.
NetworkManager[391]: <info> found WiFi radio killswitch rfkill0 (at /sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/ssb0:0/ieee80211/phy0/rfkill0) (driver (unknown))
NetworkManager[391]: <info> found WiFi radio killswitch rfkill1 (at /sys/devices/platform/hp-wmi/rfkill/rfkill1) (driver hp-wmi)
NetworkManager[391]: <info> WiFi enabled by radio killswitch; enabled by state file
NetworkManager[391]: <info> WWAN enabled by radio killswitch; enabled by state file
NetworkManager[391]: <info> WiMAX enabled by radio killswitch; enabled by state file
NetworkManager[391]: <info> Networking is enabled by state file
NetworkManager[391]: <info> (wlan0): using nl80211 for WiFi device control
NetworkManager[391]: <warn> (wlan0): driver supports Access Point (AP) mode
NetworkManager[391]: <info> (wlan0): new 802.11 WiFi device (driver: 'b43' ifindex: 3)
NetworkManager[391]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager[391]: <info> (wlan0): now managed
NetworkManager[391]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[391]: <info> (wlan0): bringing up device.
NetworkManager[391]: <info> (wlan0): preparing device.
NetworkManager[391]: <info> (wlan0): deactivating device (reason 'managed') [2]
NetworkManager[391]: <warn> failed to allocate link cache: (-10) Operation not supported
NetworkManager[391]: <info> (eth0): carrier is OFF
NetworkManager[391]: <info> (eth0): new Ethernet device (driver: 'sky2' ifindex: 2)
NetworkManager[391]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager[391]: <info> (eth0): now managed
NetworkManager[391]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[391]: <info> (eth0): bringing up device.
NetworkManager[391]: <info> (eth0): preparing device.
NetworkManager[391]: <info> (eth0): deactivating device (reason 'managed') [2]
NetworkManager[391]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1c.5/0000:85:00.0/net/eth0
/sbin/ifup: interface lo already configured
NetworkManager[391]: <warn> (ttyACM0): failed to look up interface index
NetworkManager[391]: <info> (ttyACM0): new GSM/UMTS device (driver: 'cdc_acm' ifindex: 0)
NetworkManager[391]: <info> (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/2
NetworkManager[391]: <info> (ttyACM0): now managed
NetworkManager[391]: <info> (ttyACM0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[391]: <info> (ttyACM0): deactivating device (reason 'managed') [2]
NetworkManager[391]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[391]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
NetworkManager[391]: <info> (wlan0): supplicant interface state: starting -> ready
NetworkManager[391]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
NetworkManager[391]: <info> (wlan0): supplicant interface state: ready -> inactive
NetworkManager[391]: <warn> Trying to remove a non-existant call id.
NetworkManager[391]: <info> (ttyACM0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]


NetworkManager[391]: <info> Activation (ttyACM0) starting connection 'China Mobile Internet 1'
NetworkManager[391]: <info> (ttyACM0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[391]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[391]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[391]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[391]: <info> WWAN now enabled by management service
NetworkManager[391]: <warn> GSM connection failed: (32) "Cause Select:0 Cause:0"
NetworkManager[391]: <info> (ttyACM0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
NetworkManager[391]: <warn> Activation (ttyACM0) failed.
NetworkManager[391]: <info> (ttyACM0): device state change: failed -> disconnected (reason 'none') [120 30 0]
NetworkManager[391]: <info> (ttyACM0): deactivating device (reason 'none') [0]
NetworkManager[391]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[391]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed