RaspberryPi 2 with Huawei E398

Fri May 15 01:28:52 PDT 2015

I'm trying to put together a system which uses 2 Huawei E398 USB Dongles with
two different operator SIM's so that if one modem has no coverage the other
takes over. 

I've followed the debugging information on [1] to try and get my hands on the
logs but not sure where the NetworkManger is sending its logging info. I do
have the output from ModemManager, which I'll attach to this email. 

I'm not sure that the logging from ModemManager will be of any help at all as
it might not be the issue. What is happening is that I can establish a
connection but as soon as I start to do a ping test from the command line the
whole system freezes. 

This is with one 4G Dongle in place, I was hoping to be able to manage both
connections. 

I'm using raspbian 2015-05-05 with kernel 3.18.13

If I can send on anything else please let me know. I'd really like to get this
system working. Unfortunately I've only got two E398 Dongles so I can't try
with a different modem at present. I'll try and get my hands on another.

[1] : http://www.freedesktop.org/wiki/Software/ModemManager/Debugging/
-------------- next part --------------
modem-manager[2632]: <info>  [1431616938.829890] [main.c:167] main(): ModemManager (version 0.5.2.0) starting...
modem-manager[2632]: <info>  [1431616938.850366] [mm-manager.c:120] load_plugin(): Loaded plugin Wavecom
modem-manager[2632]: <info>  [1431616938.853888] [mm-manager.c:120] load_plugin(): Loaded plugin Longcheer
modem-manager[2632]: <info>  [1431616938.858708] [mm-manager.c:120] load_plugin(): Loaded plugin Sierra
modem-manager[2632]: <info>  [1431616938.861894] [mm-manager.c:120] load_plugin(): Loaded plugin Nokia
modem-manager[2632]: <info>  [1431616938.864855] [mm-manager.c:120] load_plugin(): Loaded plugin Generic
modem-manager[2632]: <info>  [1431616938.941048] [mm-manager.c:120] load_plugin(): Loaded plugin Ericsson MBM
modem-manager[2632]: <info>  [1431616938.946348] [mm-manager.c:120] load_plugin(): Loaded plugin ZTE
modem-manager[2632]: <info>  [1431616938.949597] [mm-manager.c:120] load_plugin(): Loaded plugin Linktop
modem-manager[2632]: <info>  [1431616938.953775] [mm-manager.c:120] load_plugin(): Loaded plugin Novatel
modem-manager[2632]: <info>  [1431616938.956979] [mm-manager.c:120] load_plugin(): Loaded plugin Gobi
modem-manager[2632]: <info>  [1431616938.960090] [mm-manager.c:120] load_plugin(): Loaded plugin X22X
modem-manager[2632]: <info>  [1431616938.965179] [mm-manager.c:120] load_plugin(): Loaded plugin Samsung
modem-manager[2632]: <info>  [1431616938.969540] [mm-manager.c:120] load_plugin(): Loaded plugin Option High-Speed
modem-manager[2632]: <info>  [1431616938.972952] [mm-manager.c:120] load_plugin(): Loaded plugin SimTech
modem-manager[2632]: <info>  [1431616938.977551] [mm-manager.c:120] load_plugin(): Loaded plugin Huawei
modem-manager[2632]: <info>  [1431616938.981838] [mm-manager.c:120] load_plugin(): Loaded plugin Option
modem-manager[2632]: <info>  [1431616938.985238] [mm-manager.c:120] load_plugin(): Loaded plugin AnyData
modem-manager[2632]: <info>  [1431616939.050984] [mm-manager.c:120] load_plugin(): Loaded plugin MotoC
modem-manager[2632]: <debug> [1431616939.065680] [mm-manager.c:793] device_added(): (tty/ttyAMA0): could not get port's parent device
modem-manager[2632]: <debug> [1431616939.072508] [mm-manager.c:793] device_added(): (tty/ttyprintk): could not get port's parent device
modem-manager[2632]: <debug> [1431617007.864073] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyUSB2) deferring support check
modem-manager[2632]: <debug> [1431617007.901889] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyUSB1) deferring support check
modem-manager[2632]: <info>  [1431617007.907941] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617007.909745] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617007.910010] [mm-plugin-base.c:859] try_open(): (ttyUSB0): probe requested by plugin 'Huawei'
modem-manager[2632]: <debug> [1431617008.011120] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617008.712681] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617008.713372] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617008.715819] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CME ERROR: SIM busy<CR><LF>'
modem-manager[2632]: <debug> [1431617008.718145] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy
modem-manager[2632]: <debug> [1431617008.719036] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617009.420172] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617009.420690] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617009.421124] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CME ERROR: SIM busy<CR><LF>'
modem-manager[2632]: <debug> [1431617009.421351] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy

(modem-manager:2632): GLib-CRITICAL **: Source ID 46 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617009.422403] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617010.123444] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617010.124180] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617010.125532] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'

(modem-manager:2632): GLib-CRITICAL **: Source ID 56 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617010.126265] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617010.126438] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <info>  [1431617010.127537] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed

(modem-manager:2632): GLib-CRITICAL **: Source ID 24 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617010.140232] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB0) type primary claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617010.141675] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617010.143266] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617010.143635] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617010.143837] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 3 (open)
modem-manager[2632]: <info>  [1431617010.144674] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 claimed port ttyUSB0
modem-manager[2632]: <debug> [1431617010.144899] [mm-manager.c:317] add_modem(): Added modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617010.145048] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB0): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617010.145358] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617010.146259] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617010.151850] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT'
modem-manager[2632]: <debug> [1431617010.152713] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'E0<CR>'
modem-manager[2632]: <debug> [1431617010.153756] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.154254] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GMI<CR>'
modem-manager[2632]: <debug> [1431617010.180383] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Huawei Technologies Co., Ltd.<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.181185] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GMM<CR>'
modem-manager[2632]: <debug> [1431617010.190098] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>E398<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.190717] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GMR<CR>'
modem-manager[2632]: <debug> [1431617010.199845] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>11.836.13.00.209<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.200421] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGMI<CR>'
modem-manager[2632]: <debug> [1431617010.211230] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Huawei Technologies Co., Ltd.<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.211856] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGMM<CR>'
modem-manager[2632]: <debug> [1431617010.222426] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>E398<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.223026] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGMR<CR>'
modem-manager[2632]: <debug> [1431617010.233269] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>11.836.13.00.209<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.233865] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATI<CR>'
modem-manager[2632]: <debug> [1431617010.240298] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Manufacturer: Huawei Technologies Co., Ltd.<CR><LF>Model: E398<CR><LF>Revision: 11.836.13.00.209<CR><LF>IMEI: 354217041525637<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.241035] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATI1<CR>'
modem-manager[2632]: <debug> [1431617010.248572] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Manufacturer: Huawei Technologies Co., Ltd.<CR><LF>Model: E398<CR><LF>Revision: 11.836.13.00.209<CR><LF>IMEI: 354217041525637<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.249217] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GSN<CR>'
modem-manager[2632]: <debug> [1431617010.258671] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>354217041525637<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.259290] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGSN<CR>'
modem-manager[2632]: <debug> [1431617010.269999] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>354217041525637<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.270948] [mm-modem-helpers.c:920] mm_create_device_identifier(): Device ID source '000012d100001506Manufacturer:HuaweiTechnologiesCo.,Ltd.Model:E398Revision:11.836.13.00.209IMEI:354217041525637+GCAP:+CGSM,+DS,+ES35421704152563711.836.13.00.209E398HuaweiTechnologiesCo.,Ltd.'
modem-manager[2632]: <debug> [1431617010.271339] [mm-modem-helpers.c:921] mm_create_device_identifier(): Device ID '5f15678422017cc1cdd4082640225f2e994e9256'
modem-manager[2632]: <debug> [1431617010.271718] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 2 (close)
modem-manager[2632]: <debug> [1431617010.272028] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617010.278768] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.279664] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 1 (close)
modem-manager[2632]: <debug> [1431617010.280008] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMEE=1<CR>'
modem-manager[2632]: <debug> [1431617010.293598] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.294213] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CPIN?<CR>'
modem-manager[2632]: <debug> [1431617010.305844] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.306526] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617010.306840] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 1 (close)
modem-manager[2632]: <debug> [1431617010.307226] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[2632]: <debug> [1431617010.500107] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>^STIN:99,1,0<CR><LF>'
modem-manager[2632]: <debug> [1431617010.563328] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CRSM: 144,0,"985303151020086211F7"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617010.564524] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617010.564989] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <info>  [1431617010.566324] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed
modem-manager[2632]: <debug> [1431617010.567404] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617010.865532] [mm-manager.c:457] supports_defer_timeout(): (ttyUSB2): re-checking support...
modem-manager[2632]: <info>  [1431617010.869600] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB2) opening serial port...
modem-manager[2632]: <debug> [1431617010.871629] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617010.903453] [mm-manager.c:457] supports_defer_timeout(): (ttyUSB1): re-checking support...
modem-manager[2632]: <info>  [1431617010.907036] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB1) opening serial port...
modem-manager[2632]: <debug> [1431617010.909071] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617010.913657] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^RSSI:12<CR><LF><CR><LF>^CSNR:-93,-4<CR>'
modem-manager[2632]: <debug> [1431617010.914313] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<LF><CR><LF>^STIN:99,1,0<CR><LF><CR><LF>^SIMST:1<CR><LF>'
modem-manager[2632]: <debug> [1431617010.914947] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617010.915113] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB1) closing serial port...
modem-manager[2632]: <info>  [1431617010.916204] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB1) serial port closed
modem-manager[2632]: <debug> [1431617010.921700] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB1) type secondary claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617010.923571] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 claimed port ttyUSB1

(modem-manager:2632): GLib-CRITICAL **: Source ID 34 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617010.924252] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB2): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617018.419280] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617018.419606] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB2) closing serial port...
modem-manager[2632]: <info>  [1431617018.421001] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB2) serial port closed
modem-manager[2632]: <debug> [1431617018.424243] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB2) type ignored claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617018.425945] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 claimed port ttyUSB2

(modem-manager:2632): GLib-CRITICAL **: Source ID 32 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617018.426900] [mm-manager.c:265] check_export_modem(): Exported modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 as /org/freedesktop/ModemManager/Modems/0
modem-manager[2632]: <debug> [1431617018.427310] [mm-manager.c:278] check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): VID 0x12D1 PID 0x1506 (usb)
modem-manager[2632]: <debug> [1431617018.427512] [mm-manager.c:279] check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): data port is ttyUSB0
modem-manager[2632]: <info>  [1431617023.988265] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617023.989797] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <info>  [1431617023.990514] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
modem-manager[2632]: <debug> [1431617024.091576] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATZ E0 V1<CR>'
modem-manager[2632]: <debug> [1431617024.120694] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.121567] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATZ E0 V1<CR>'
modem-manager[2632]: <debug> [1431617024.144419] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.145324] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617024.156342] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.157088] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMEE=1<CR>'
modem-manager[2632]: <debug> [1431617024.169544] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.170187] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATX4 &C1<CR>'
modem-manager[2632]: <debug> [1431617024.182197] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.182916] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CFUN=1<CR>'
modem-manager[2632]: <debug> [1431617024.195732] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <info>  [1431617024.196376] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB1) opening serial port...
modem-manager[2632]: <debug> [1431617024.197515] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617024.197848] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617024.198307] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+IFC=1,1<CR>'
modem-manager[2632]: <debug> [1431617024.242890] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.243689] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CPMS="ME","ME","ME"<CR>'
modem-manager[2632]: <debug> [1431617024.244784] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^SRVST:2<CR><LF>'
modem-manager[2632]: <debug> [1431617024.287894] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CPMS: 0,50,0,50,0,50<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.288939] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CNMI=2,1,2,1,0<CR>'
modem-manager[2632]: <debug> [1431617024.313419] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
modem-manager[2632]: <debug> [1431617024.313981] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
modem-manager[2632]: <debug> [1431617024.314529] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMGF=?<CR>'
modem-manager[2632]: <debug> [1431617024.327834] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.328752] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CUSD=1<CR>'
modem-manager[2632]: <debug> [1431617024.348890] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.349930] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CIND=?<CR>'
modem-manager[2632]: <debug> [1431617024.373144] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("GPRS coverage",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.374306] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[2632]: <debug> [1431617024.446493] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CRSM: 144,0,"985303151020086211F7"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.447495] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 1 (close)
modem-manager[2632]: <debug> [1431617024.448247] [mm-generic-gsm.c:507] get_iccid_done(): SIM ID source '8935305101028026117'
modem-manager[2632]: <debug> [1431617024.448752] [mm-generic-gsm.c:508] get_iccid_done(): SIM ID 'b3b20c3508336f2a1f37bf093f2475912480eb2d'
modem-manager[2632]: <debug> [1431617024.449214] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT^SYSCFG?<CR>'
modem-manager[2632]: <debug> [1431617024.463775] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>COMMAND NOT SUPPORT<CR><LF>'
modem-manager[2632]: <debug> [1431617024.464507] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[2632]: <debug> [1431617024.465145] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CSCS=?<CR>'
modem-manager[2632]: <debug> [1431617024.478447] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.479486] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMGF=1<CR>'
modem-manager[2632]: <debug> [1431617024.492612] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <info>  [1431617024.493102] [mm-generic-gsm.c:1704] sms_set_format_cb(): (ttyUSB0): using text mode for SMS
modem-manager[2632]: <debug> [1431617024.493573] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[2632]: <debug> [1431617024.514739] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.515322] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[2632]: <debug> [1431617024.515821] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CSCS="UCS2"<CR>'
modem-manager[2632]: <debug> [1431617024.536732] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.537605] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CSCS?<CR>'
modem-manager[2632]: <debug> [1431617024.538771] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.550119] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <info>  [1431617024.551956] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> enabled)
modem-manager[2632]: <debug> [1431617024.553801] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CREG=2<CR>'
modem-manager[2632]: <debug> [1431617024.568230] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.569987] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CIND?<CR>'
modem-manager[2632]: <debug> [1431617024.584947] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.586060] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CREG?<CR>'
modem-manager[2632]: <debug> [1431617024.597714] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CREG: 2,1, 3C, 2BD0308, 2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.598490] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): CS registration state changed: 1
modem-manager[2632]: <info>  [1431617024.599390] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabled -> registered)
modem-manager[2632]: <debug> [1431617024.602168] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGREG=2<CR>'
modem-manager[2632]: <debug> [1431617024.644498] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.645379] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): --> 'AT+CREG=2<CR>'
modem-manager[2632]: <debug> [1431617024.646016] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[2632]: <debug> [1431617024.649275] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): number => "*99#"
modem-manager[2632]: <debug> [1431617024.649696] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): network_mode => 0
modem-manager[2632]: <debug> [1431617024.649958] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): apn => "3ireland.ie"
modem-manager[2632]: <debug> [1431617024.650194] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): allowed_mode => 0
modem-manager[2632]: <debug> [1431617024.650430] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): allowed_auth => 63
modem-manager[2632]: <debug> [1431617024.650711] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 0
modem-manager[2632]: <debug> [1431617024.651566] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 2
modem-manager[2632]: <debug> [1431617024.667940] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.668837] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): --> 'AT+CGREG=2<CR>'
modem-manager[2632]: <debug> [1431617024.677160] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+COPS: 0,2,"27205",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.678551] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[2632]: <debug> [1431617024.687787] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.707555] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+COPS: 0,0,"3 IRL",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.708812] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CIND?<CR>'
modem-manager[2632]: <debug> [1431617024.760005] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.761695] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT^SYSINFO<CR>'
modem-manager[2632]: <debug> [1431617024.787900] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>^SYSINFO:2,1,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.788858] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGREG?<CR>'
modem-manager[2632]: <debug> [1431617024.802357] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.803201] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CREG?<CR>'
modem-manager[2632]: <debug> [1431617024.822480] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CREG: 2,1, 3C, 2BD0308, 2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.823337] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGREG?<CR>'
modem-manager[2632]: <debug> [1431617024.836141] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.836890] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 4
modem-manager[2632]: <debug> [1431617024.837314] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGDCONT?<CR>'
modem-manager[2632]: <debug> [1431617024.856507] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGDCONT: 1,"IP","3ireland.ie","0.0.0.0",0,0<CR><LF>+CGDCONT: 2,"IP","hs.vodafone.ie","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.857479] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 5
modem-manager[2632]: <info>  [1431617024.858200] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
modem-manager[2632]: <debug> [1431617024.858628] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATD*99***1#<CR>'
modem-manager[2632]: <debug> [1431617024.876349] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>CONNECT 43200000<CR><LF>'
modem-manager[2632]: <debug> [1431617024.876935] [mm-port.c:181] mm_port_set_connected(): (ttyUSB0): port now connected
modem-manager[2632]: <info>  [1431617024.877589] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (connecting -> connected)
modem-manager[2632]: <debug> [1431617024.877970] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 6
modem-manager[2632]: <debug> [1431617024.891828] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): --> 'AT+CIND?<CR>'
modem-manager[2632]: <debug> [1431617024.914915] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617024.916354] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): --> 'AT+CIND?<CR>'
modem-manager[2632]: <debug> [1431617024.932752] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617025.115162] [mm-manager.c:793] device_added(): (net/ppp0): could not get port's parent device
modem-manager[2632]: <debug> [1431617026.639118] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^RSSI:12<CR><LF>'
modem-manager[2632]: <debug> [1431617026.640655] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^CSNR:-95,-7<CR><LF>'
modem-manager[2632]: <debug> [1431617033.901493] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^RSSI:99<CR><LF>'
modem-manager[2632]: <debug> [1431617033.903559] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^SRVST:0<CR><LF><CR><LF>^MODE:0,0<CR><LF><CR><LF>+CREG: 2<CR><LF><CR><LF>+CGREG: 2<CR><LF>'
modem-manager[2632]: <debug> [1431617033.903821] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): CS registration state changed: 2
modem-manager[2632]: <debug> [1431617033.904470] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): PS registration state changed: 2
modem-manager[2632]: <debug> [1431617033.904903] [mm-modem-huawei-gsm.c:709] handle_mode_change(): Access Technology: 0
modem-manager[2632]: <debug> [1431617037.661502] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^BOOT:41525636,0,0,0,99<CR><LF>'
modem-manager[2632]: <debug> [1431617038.171398] [mm-serial-port.c:636] data_available(): (ttyUSB0) unexpected port hangup!
modem-manager[2632]: <debug> [1431617038.171905] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617038.172137] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <debug> [1431617038.172393] [mm-port.c:181] mm_port_set_connected(): (ttyUSB0): port now disconnected
modem-manager[2632]: <info>  [1431617038.172618] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed
modem-manager[2632]: <info>  [1431617038.176781] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (connected -> disconnecting)
modem-manager[2632]: <info>  [1431617038.178098] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (disconnecting -> connected)
modem-manager[2632]: <debug> [1431617039.194727] [mm-serial-port.c:636] data_available(): (ttyUSB1) unexpected port hangup!
modem-manager[2632]: <debug> [1431617039.195090] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617039.195321] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB1) closing serial port...
modem-manager[2632]: <info>  [1431617039.195745] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB1) serial port closed
modem-manager[2632]: <info>  [1431617039.198633] [mm-manager.c:862] device_removed(): (tty/ttyUSB2): released by modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617039.206242] [mm-manager.c:862] device_removed(): (tty/ttyUSB1): released by modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617039.206782] [mm-modem-base.c:185] mm_modem_base_remove_port(): (ttyUSB1) type secondary removed from /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617039.213114] [mm-manager.c:862] device_removed(): (tty/ttyUSB0): released by modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617039.213755] [mm-modem-base.c:185] mm_modem_base_remove_port(): (ttyUSB0) type primary removed from /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617039.221129] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (connected -> disabled)
modem-manager[2632]: <debug> [1431617039.221606] [mm-manager.c:204] remove_modem(): Removed modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617056.749166] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyUSB1) deferring support check
modem-manager[2632]: <debug> [1431617056.775441] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyUSB2) deferring support check
modem-manager[2632]: <info>  [1431617056.811165] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617056.812832] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617056.813100] [mm-plugin-base.c:859] try_open(): (ttyUSB0): probe requested by plugin 'Huawei'
modem-manager[2632]: <debug> [1431617056.913998] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617057.615490] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617057.616093] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617057.618202] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CME ERROR: SIM busy<CR><LF>'
modem-manager[2632]: <debug> [1431617057.618589] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy
modem-manager[2632]: <debug> [1431617057.619185] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617058.320409] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617058.321120] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617058.322413] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'

(modem-manager:2632): GLib-CRITICAL **: Source ID 750 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617058.323170] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617058.323350] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <info>  [1431617058.324412] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed

(modem-manager:2632): GLib-CRITICAL **: Source ID 41 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617058.334938] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB0) type primary claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617058.336413] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617058.337804] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617058.338233] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617058.338600] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 3 (open)
modem-manager[2632]: <info>  [1431617058.339576] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 claimed port ttyUSB0
modem-manager[2632]: <debug> [1431617058.339957] [mm-manager.c:317] add_modem(): Added modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617058.340225] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB0): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617058.340684] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617058.341784] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617058.346802] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617058.353088] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.353750] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GMI<CR>'
modem-manager[2632]: <debug> [1431617058.364076] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Huawei Technologies Co., Ltd.<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.364734] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GMM<CR>'
modem-manager[2632]: <debug> [1431617058.373646] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>E398<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.374227] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GMR<CR>'
modem-manager[2632]: <debug> [1431617058.383619] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>11.836.13.00.209<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.384231] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGMI<CR>'
modem-manager[2632]: <debug> [1431617058.394932] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Huawei Technologies Co., Ltd.<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.395488] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGMM<CR>'
modem-manager[2632]: <debug> [1431617058.406140] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>E398<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.406707] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGMR<CR>'
modem-manager[2632]: <debug> [1431617058.417460] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>11.836.13.00.209<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.418072] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATI<CR>'
modem-manager[2632]: <debug> [1431617058.424438] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Manufacturer: Huawei Technologies Co., Ltd.<CR><LF>Model: E398<CR><LF>Revision: 11.836.13.00.209<CR><LF>IMEI: 354217041525637<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.425132] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATI1<CR>'
modem-manager[2632]: <debug> [1431617058.432823] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>Manufacturer: Huawei Technologies Co., Ltd.<CR><LF>Model: E398<CR><LF>Revision: 11.836.13.00.209<CR><LF>IMEI: 354217041525637<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.433696] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GSN<CR>'
modem-manager[2632]: <debug> [1431617058.443420] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>354217041525637<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.444063] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGSN<CR>'
modem-manager[2632]: <debug> [1431617058.455172] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>354217041525637<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.455867] [mm-modem-helpers.c:920] mm_create_device_identifier(): Device ID source '000012d100001506Manufacturer:HuaweiTechnologiesCo.,Ltd.Model:E398Revision:11.836.13.00.209IMEI:354217041525637+GCAP:+CGSM,+DS,+ES35421704152563711.836.13.00.209E398HuaweiTechnologiesCo.,Ltd.'
modem-manager[2632]: <debug> [1431617058.456043] [mm-modem-helpers.c:921] mm_create_device_identifier(): Device ID '5f15678422017cc1cdd4082640225f2e994e9256'
modem-manager[2632]: <debug> [1431617058.456302] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 2 (close)
modem-manager[2632]: <debug> [1431617058.456451] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617058.462909] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.463710] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 1 (close)
modem-manager[2632]: <debug> [1431617058.463885] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMEE=1<CR>'
modem-manager[2632]: <debug> [1431617058.477283] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.477756] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CPIN?<CR>'
modem-manager[2632]: <debug> [1431617058.489634] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617058.490178] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617058.490362] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 1 (close)
modem-manager[2632]: <debug> [1431617058.490620] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[2632]: <debug> [1431617059.374020] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>^STIN:99,1,0<CR><LF>'
modem-manager[2632]: <debug> [1431617059.413779] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CRSM: 144,0,"985303151020086211F7"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617059.415200] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617059.416280] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <info>  [1431617059.418246] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed
modem-manager[2632]: <debug> [1431617059.419732] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB1): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617059.750687] [mm-manager.c:457] supports_defer_timeout(): (ttyUSB1): re-checking support...
modem-manager[2632]: <info>  [1431617059.754485] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB1) opening serial port...
modem-manager[2632]: <debug> [1431617059.756759] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617059.759822] [mm-at-serial-port.c:333] debug_log(): (ttyUSB1): <-- '<CR><LF>^STIN:99,1,0<CR><LF><CR><LF>^SIMST:1<CR><LF>'
modem-manager[2632]: <debug> [1431617059.776534] [mm-manager.c:457] supports_defer_timeout(): (ttyUSB2): re-checking support...
modem-manager[2632]: <info>  [1431617059.780205] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB2) opening serial port...
modem-manager[2632]: <debug> [1431617059.781404] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617067.418887] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617067.419254] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB1) closing serial port...
modem-manager[2632]: <info>  [1431617067.420088] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB1) serial port closed
modem-manager[2632]: <debug> [1431617067.420513] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617067.420687] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB2) closing serial port...
modem-manager[2632]: <info>  [1431617067.421542] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB2) serial port closed
modem-manager[2632]: <debug> [1431617067.425135] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB1) type ignored claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617067.427094] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 claimed port ttyUSB1

(modem-manager:2632): GLib-CRITICAL **: Source ID 734 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617067.427814] [mm-manager.c:243] check_export_modem(): (tty/ttyUSB2): outstanding support task prevents export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617067.431148] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB2) type ignored claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617067.432916] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 claimed port ttyUSB2

(modem-manager:2632): GLib-CRITICAL **: Source ID 736 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617067.433942] [mm-manager.c:265] check_export_modem(): Exported modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 as /org/freedesktop/ModemManager/Modems/1
modem-manager[2632]: <debug> [1431617067.434355] [mm-manager.c:278] check_export_modem(): (/org/freedesktop/ModemManager/Modems/1): VID 0x12D1 PID 0x1506 (usb)
modem-manager[2632]: <debug> [1431617067.434489] [mm-manager.c:279] check_export_modem(): (/org/freedesktop/ModemManager/Modems/1): data port is ttyUSB0
modem-manager[2632]: <info>  [1431617074.547239] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617074.548791] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <info>  [1431617074.549480] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/1: state changed (disabled -> enabling)
modem-manager[2632]: <debug> [1431617074.650588] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATZ E0 V1<CR>'
modem-manager[2632]: <debug> [1431617074.672412] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.673275] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATZ E0 V1<CR>'
modem-manager[2632]: <debug> [1431617074.691349] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.692057] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATE0<CR>'
modem-manager[2632]: <debug> [1431617074.698493] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.699036] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMEE=1<CR>'
modem-manager[2632]: <debug> [1431617074.711339] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.711908] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATX4 &C1<CR>'
modem-manager[2632]: <debug> [1431617074.723364] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.723898] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CFUN=1<CR>'
modem-manager[2632]: <debug> [1431617074.736394] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.736919] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617074.737378] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+IFC=1,1<CR>'
modem-manager[2632]: <debug> [1431617074.751241] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.752168] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CPMS="ME","ME","ME"<CR>'
modem-manager[2632]: <debug> [1431617074.787346] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CPMS: 0,50,0,50,0,50<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.788077] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CNMI=2,1,2,1,0<CR>'
modem-manager[2632]: <debug> [1431617074.810101] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
modem-manager[2632]: <debug> [1431617074.810579] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
modem-manager[2632]: <debug> [1431617074.811043] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMGF=?<CR>'
modem-manager[2632]: <debug> [1431617074.823334] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.824084] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CUSD=1<CR>'
modem-manager[2632]: <debug> [1431617074.837526] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.838060] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CIND=?<CR>'
modem-manager[2632]: <debug> [1431617074.851774] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("GPRS coverage",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.853169] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[2632]: <debug> [1431617074.923769] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CRSM: 144,0,"985303151020086211F7"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.924446] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 1 (close)
modem-manager[2632]: <debug> [1431617074.924931] [mm-generic-gsm.c:507] get_iccid_done(): SIM ID source '8935305101028026117'
modem-manager[2632]: <debug> [1431617074.925205] [mm-generic-gsm.c:508] get_iccid_done(): SIM ID 'b3b20c3508336f2a1f37bf093f2475912480eb2d'
modem-manager[2632]: <debug> [1431617074.925545] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT^SYSCFG?<CR>'
modem-manager[2632]: <debug> [1431617074.939125] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>COMMAND NOT SUPPORT<CR><LF>'
modem-manager[2632]: <debug> [1431617074.939629] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[2632]: <debug> [1431617074.940092] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CSCS=?<CR>'
modem-manager[2632]: <debug> [1431617074.953023] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.954428] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMGF=1<CR>'
modem-manager[2632]: <debug> [1431617074.967054] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <info>  [1431617074.967630] [mm-generic-gsm.c:1704] sms_set_format_cb(): (ttyUSB0): using text mode for SMS
modem-manager[2632]: <debug> [1431617074.968044] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[2632]: <debug> [1431617074.987705] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617074.988286] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CSCS="UCS2"<CR>'
modem-manager[2632]: <debug> [1431617075.006749] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.007320] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CSCS?<CR>'
modem-manager[2632]: <debug> [1431617075.018525] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <info>  [1431617075.020432] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/1: state changed (enabling -> enabled)
modem-manager[2632]: <debug> [1431617075.023345] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CREG=2<CR>'
modem-manager[2632]: <debug> [1431617075.037206] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.040254] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CIND?<CR>'
modem-manager[2632]: <debug> [1431617075.057103] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.058765] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CREG?<CR>'
modem-manager[2632]: <debug> [1431617075.070211] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CREG: 2,1, 3C, 2BD1121, 2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.070671] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): CS registration state changed: 1
modem-manager[2632]: <info>  [1431617075.072077] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/1: state changed (enabled -> registered)
modem-manager[2632]: <debug> [1431617075.075715] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGREG=2<CR>'
modem-manager[2632]: <debug> [1431617075.089805] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.091326] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[2632]: <debug> [1431617075.093558] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): number => "*99#"
modem-manager[2632]: <debug> [1431617075.093810] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): network_mode => 0
modem-manager[2632]: <debug> [1431617075.093938] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): apn => "3ireland.ie"
modem-manager[2632]: <debug> [1431617075.094067] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): allowed_mode => 0
modem-manager[2632]: <debug> [1431617075.094186] [mm-generic-gsm.c:5924] simple_connect(): (ttyUSB0): allowed_auth => 63
modem-manager[2632]: <debug> [1431617075.094345] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 0
modem-manager[2632]: <debug> [1431617075.094841] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 2
modem-manager[2632]: <debug> [1431617075.118724] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+COPS: 0,2,"27205",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.119747] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[2632]: <debug> [1431617075.145220] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+COPS: 0,0,"3 IRL",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.146514] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CIND?<CR>'
modem-manager[2632]: <debug> [1431617075.158500] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.159304] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT^SYSINFO<CR>'
modem-manager[2632]: <debug> [1431617075.173406] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>^SYSINFO:2,3,0,5,1,,4<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.174171] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGREG?<CR>'
modem-manager[2632]: <debug> [1431617075.187012] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.187647] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CREG?<CR>'
modem-manager[2632]: <debug> [1431617075.198661] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CREG: 2,1, 3C, 2BD1121, 2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.199407] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGREG?<CR>'
modem-manager[2632]: <debug> [1431617075.211888] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGREG: 2,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.212529] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 4
modem-manager[2632]: <debug> [1431617075.212887] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+CGDCONT?<CR>'
modem-manager[2632]: <debug> [1431617075.230667] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGDCONT: 1,"IP","3ireland.ie","0.0.0.0",0,0<CR><LF>+CGDCONT: 2,"IP","hs.vodafone.ie","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[2632]: <debug> [1431617075.231600] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 5
modem-manager[2632]: <info>  [1431617075.232309] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/1: state changed (registered -> connecting)
modem-manager[2632]: <debug> [1431617075.232717] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'ATD*99***1#<CR>'
modem-manager[2632]: <debug> [1431617075.250014] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>CONNECT 43200000<CR><LF>'
modem-manager[2632]: <debug> [1431617075.250506] [mm-port.c:181] mm_port_set_connected(): (ttyUSB0): port now connected
modem-manager[2632]: <info>  [1431617075.251227] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/1: state changed (connecting -> connected)
modem-manager[2632]: <debug> [1431617075.251639] [mm-generic-gsm.c:5813] simple_state_machine(): (ttyUSB0): simple connect state 6
modem-manager[2632]: <debug> [1431617075.389628] [mm-manager.c:793] device_added(): (net/ppp0): could not get port's parent device
modem-manager[2632]: <info>  [1431617088.100879] [mm-manager.c:862] device_removed(): (tty/ttyUSB0): released by modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617088.101365] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617088.101689] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <debug> [1431617088.101971] [mm-port.c:181] mm_port_set_connected(): (ttyUSB0): port now disconnected
modem-manager[2632]: <info>  [1431617088.102298] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed
modem-manager[2632]: <debug> [1431617088.102833] [mm-modem-base.c:185] mm_modem_base_remove_port(): (ttyUSB0) type primary removed from /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617088.103878] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/1: state changed (connected -> disabled)
modem-manager[2632]: <debug> [1431617088.104264] [mm-manager.c:204] remove_modem(): Removed modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <debug> [1431617105.352079] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyUSB1) deferring support check
modem-manager[2632]: <debug> [1431617105.399298] [mm-manager.c:491] try_supports_port(): (Huawei): (ttyUSB2) deferring support check
modem-manager[2632]: <info>  [1431617105.452104] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617105.454144] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617105.454474] [mm-plugin-base.c:859] try_open(): (ttyUSB0): probe requested by plugin 'Huawei'
modem-manager[2632]: <debug> [1431617105.555708] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617106.257462] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617106.258243] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617106.259647] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CME ERROR: SIM busy<CR><LF>'
modem-manager[2632]: <debug> [1431617106.260035] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy
modem-manager[2632]: <debug> [1431617106.260692] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617106.962087] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617106.962790] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617106.965021] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+CME ERROR: SIM busy<CR><LF>'
modem-manager[2632]: <debug> [1431617106.965286] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy

(modem-manager:2632): GLib-CRITICAL **: Source ID 1378 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617106.965970] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[2632]: <debug> [1431617107.667027] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- 'AT+GCAP'
modem-manager[2632]: <debug> [1431617107.667859] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR>'
modem-manager[2632]: <debug> [1431617107.669202] [mm-at-serial-port.c:333] debug_log(): (ttyUSB0): <-- '<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'

(modem-manager:2632): GLib-CRITICAL **: Source ID 1388 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617107.670012] [mm-serial-port.c:844] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[2632]: <info>  [1431617107.670194] [mm-serial-port.c:859] mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[2632]: <info>  [1431617107.671359] [mm-serial-port.c:880] mm_serial_port_close(): (ttyUSB0) serial port closed

(modem-manager:2632): GLib-CRITICAL **: Source ID 57 was not found when attempting to remove it
modem-manager[2632]: <debug> [1431617107.682075] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyUSB0) type primary claimed by /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2
modem-manager[2632]: <info>  [1431617107.683952] [mm-serial-port.c:741] mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[2632]: <debug> [1431617107.685888] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[2632]: <debug> [1431617107.686443] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 2 (open)
modem-manager[2632]: <debug> [1431617107.686919] [mm-serial-port.c:810] mm_serial_port_open(): (ttyUSB0) device open count is 3 (open)
modem-manager[2632]: <info>  [1431617107.687954] [mm-manager.c:564] do_grab_port(): (Huawei): GSM modem /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.2 clai